builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-074 starttime: 1506338550.45 results: success (0) revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.448374) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.448822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.449126) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.528446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.528724) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 04:22:30-- 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% 28.5M=0s 2017-09-25 04:22:30 (28.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.674830) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.675166) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017571 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.724988) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.725325) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 --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 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 --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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 04:22:30,766 truncating revision to first 12 chars 2017-09-25 04:22:30,766 Setting DEBUG logging. 2017-09-25 04:22:30,766 attempt 1/10 2017-09-25 04:22:30,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f3f19824efa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 04:22:31,083 unpacking tar archive at: mozilla-central-5f3f19824efa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.593549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.335287) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.335608) ========= script_repo_revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.336121) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.336385) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.350689) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-25 04:22:31.351098) ========= /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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:22:31 INFO - MultiFileLogger online at 20170925 04:22:31 in /builds/slave/test 04:22:31 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 04:22:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:31 INFO - {'append_to_log': False, 04:22:31 INFO - 'base_work_dir': '/builds/slave/test', 04:22:31 INFO - 'blob_upload_branch': 'Firefox', 04:22:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:31 INFO - 'branch': 'Firefox', 04:22:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:31 INFO - 'code_coverage': False, 04:22:31 INFO - 'config_files': ('talos/linux_config.py',), 04:22:31 INFO - 'default_actions': ('clobber', 04:22:31 INFO - 'read-buildbot-config', 04:22:31 INFO - 'download-and-extract', 04:22:31 INFO - 'populate-webroot', 04:22:31 INFO - 'create-virtualenv', 04:22:31 INFO - 'install', 04:22:31 INFO - 'setup-mitmproxy', 04:22:31 INFO - 'run-tests'), 04:22:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:31 INFO - 'disable_ccov_upload': False, 04:22:31 INFO - 'disable_stylo': False, 04:22:31 INFO - 'download_minidump_stackwalk': True, 04:22:31 INFO - 'download_symbols': 'ondemand', 04:22:31 INFO - 'enable_stylo': False, 04:22:31 INFO - 'enable_webrender': False, 04:22:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:22:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:31 INFO - 'gecko_profile': False, 04:22:31 INFO - 'gecko_profile_interval': 0, 04:22:31 INFO - 'installer_path': 'installer.exe', 04:22:31 INFO - 'log_level': 'info', 04:22:31 INFO - 'log_name': 'talos', 04:22:31 INFO - 'log_to_console': True, 04:22:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:22:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:22:31 INFO - 'opt_config_files': (), 04:22:31 INFO - 'pip_index': False, 04:22:31 INFO - 'suite': 'dromaeojs-e10s', 04:22:31 INFO - 'system_bits': '32', 04:22:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:31 INFO - 'title': 'talos-linux64-ix-074', 04:22:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:31 INFO - 'use_talos_json': True, 04:22:31 INFO - 'verify': 'False', 04:22:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:31 INFO - 'work_dir': 'build'} 04:22:31 INFO - [mozharness: 2017-09-25 11:22:31.529830Z] Running clobber step. 04:22:31 INFO - Running pre-action listener: _resource_record_pre_action 04:22:31 INFO - Running main action method: clobber 04:22:31 INFO - rmtree: /builds/slave/test/build 04:22:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:33 INFO - Running post-action listener: _resource_record_post_action 04:22:33 INFO - [mozharness: 2017-09-25 11:22:33.025596Z] Finished clobber step (success) 04:22:33 INFO - [mozharness: 2017-09-25 11:22:33.025694Z] Running read-buildbot-config step. 04:22:33 INFO - Running pre-action listener: _resource_record_pre_action 04:22:33 INFO - Running main action method: read_buildbot_config 04:22:33 INFO - Using buildbot properties: 04:22:33 INFO - { 04:22:33 INFO - "project": "", 04:22:33 INFO - "product": "firefox", 04:22:33 INFO - "who": "archaeopteryx@coole-files.de", 04:22:33 INFO - "installer_path": "public/build/target.tar.bz2", 04:22:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:22:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:22:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:22:33 INFO - "stage_platform": "linux64-pgo", 04:22:33 INFO - "basedir": "/builds/slave/test", 04:22:33 INFO - "buildnumber": 203, 04:22:33 INFO - "platform": "ubuntu64_hw", 04:22:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:22:33 INFO - "slavebuilddir": "test", 04:22:33 INFO - "taskId": "CO0jckURSjmHnBwwHBDxNA", 04:22:33 INFO - "branch": "mozilla-central", 04:22:33 INFO - "script_repo_revision": "production", 04:22:33 INFO - "revision": "5f3f19824efa14cc6db546baf59c54a0fc15ddc9", 04:22:33 INFO - "slavename": "talos-linux64-ix-074", 04:22:33 INFO - "repo_path": "mozilla-central" 04:22:33 INFO - } 04:22:33 INFO - Finding installer, test and symbols from parent task. 04:22:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CO0jckURSjmHnBwwHBDxNA'}, attempt #1 04:22:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CO0jckURSjmHnBwwHBDxNA'}, attempt #1 04:22:33 INFO - Task dependencies: EQ7NM8uLRAmZjEZGwDse2A 04:22:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A'}, attempt #1 04:22:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 04:22:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.test_packages.json 04:22:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.crashreporter-symbols.zip 04:22:34 INFO - Running post-action listener: _resource_record_post_action 04:22:34 INFO - [mozharness: 2017-09-25 11:22:34.377521Z] Finished read-buildbot-config step (success) 04:22:34 INFO - [mozharness: 2017-09-25 11:22:34.377744Z] Running download-and-extract step. 04:22:34 INFO - Running pre-action listener: _resource_record_pre_action 04:22:34 INFO - Running main action method: download_and_extract 04:22:34 INFO - mkdir: /builds/slave/test/build/tests 04:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:34 INFO - trying https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.test_packages.json 04:22:34 INFO - Downloading https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:22:34 INFO - Downloaded 989 bytes. 04:22:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:22:34 INFO - Using the following test package requirements: 04:22:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:22:34 INFO - u'common': [u'target.common.tests.zip'], 04:22:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:22:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:22:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:22:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:22:34 INFO - u'mozbase': [u'target.common.tests.zip'], 04:22:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:22:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:22:34 INFO - u'web-platform': [u'target.common.tests.zip', 04:22:34 INFO - u'target.web-platform.tests.tar.gz'], 04:22:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:22:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:22:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.common.tests.zip 04:22:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:22:34 INFO - Fetch https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.common.tests.zip into memory 04:22:35 INFO - Content-Length response header: 55346078 04:22:35 INFO - Bytes received: 55346078 04:22:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.talos.tests.zip 04:22:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:22:46 INFO - Fetch https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.talos.tests.zip into memory 04:22:47 INFO - Content-Length response header: 14513823 04:22:47 INFO - Bytes received: 14513823 04:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:47 INFO - trying https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 04:22:47 INFO - Downloading https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:22:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:22:48 INFO - Downloaded 62411662 bytes. 04:22:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 04:22:48 INFO - mkdir: /builds/slave/test/properties 04:22:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:22:48 INFO - Writing to file /builds/slave/test/properties/build_url 04:22:48 INFO - Contents: 04:22:48 INFO - build_url:https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 04:22:48 INFO - Running post-action listener: _resource_record_post_action 04:22:48 INFO - Running post-action listener: find_tests_for_verification 04:22:48 INFO - Running post-action listener: set_extra_try_arguments 04:22:48 INFO - [mozharness: 2017-09-25 11:22:48.891029Z] Finished download-and-extract step (success) 04:22:48 INFO - [mozharness: 2017-09-25 11:22:48.891183Z] Running populate-webroot step. 04:22:48 INFO - Running pre-action listener: _resource_record_pre_action 04:22:48 INFO - Running main action method: populate_webroot 04:22:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:22:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['tresize', 04:22:48 INFO - 'tcanvasmark']}, 04:22:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:22:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['dromaeo_css', 04:22:48 INFO - 'kraken']}, 04:22:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'tests': ['damp', 'tps']}, 04:22:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['damp', 'tps']}, 04:22:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:22:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['dromaeo_dom']}, 04:22:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:22:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['basic_compositor_video', 04:22:48 INFO - 'glvideo']}, 04:22:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:22:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['ts_paint_webext', 04:22:48 INFO - 'tp5o_webext']}, 04:22:48 INFO - 'other-e10s': {'tests': ['a11yr', 04:22:48 INFO - 'ts_paint', 04:22:48 INFO - 'tpaint', 04:22:48 INFO - 'sessionrestore', 04:22:48 INFO - 'sessionrestore_many_windows', 04:22:48 INFO - 'sessionrestore_no_auto_restore', 04:22:48 INFO - 'tabpaint']}, 04:22:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['a11yr', 04:22:48 INFO - 'ts_paint', 04:22:48 INFO - 'tpaint', 04:22:48 INFO - 'sessionrestore', 04:22:48 INFO - 'sessionrestore_many_windows', 04:22:48 INFO - 'sessionrestore_no_auto_restore', 04:22:48 INFO - 'tabpaint']}, 04:22:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:22:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:22:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['perf_reftest_singletons']}, 04:22:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['perf_reftest']}, 04:22:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:22:48 INFO - 'tsvgr_opacity', 04:22:48 INFO - 'tart', 04:22:48 INFO - 'tscrollx', 04:22:48 INFO - 'tsvg_static']}, 04:22:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['tsvgx', 04:22:48 INFO - 'tsvgr_opacity', 04:22:48 INFO - 'tart', 04:22:48 INFO - 'tscrollx', 04:22:48 INFO - 'tsvg_static']}, 04:22:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:22:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--disable-stylo'], 04:22:48 INFO - 'tests': ['tp5o']}, 04:22:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:48 INFO - 'talos_options': ['--mitmproxy', 04:22:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:48 INFO - '--firstNonBlankPaint'], 04:22:48 INFO - 'tests': ['tp6_google', 04:22:48 INFO - 'tp6_youtube', 04:22:48 INFO - 'tp6_amazon', 04:22:48 INFO - 'tp6_facebook']}, 04:22:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:48 INFO - 'talos_options': ['--disable-stylo', 04:22:48 INFO - '--mitmproxy', 04:22:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:48 INFO - '--firstNonBlankPaint'], 04:22:48 INFO - 'tests': ['tp6_google', 04:22:48 INFO - 'tp6_youtube', 04:22:48 INFO - 'tp6_amazon', 04:22:48 INFO - 'tp6_facebook']}, 04:22:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:48 INFO - 'talos_options': ['--stylo-threads=1', 04:22:48 INFO - '--mitmproxy', 04:22:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:48 INFO - '--firstNonBlankPaint'], 04:22:48 INFO - 'tests': ['tp6_google', 04:22:48 INFO - 'tp6_youtube', 04:22:48 INFO - 'tp6_amazon', 04:22:48 INFO - 'tp6_facebook']}, 04:22:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--xperf_path', 04:22:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:48 INFO - 'tests': ['tp5n']}, 04:22:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:48 INFO - 'talos_options': ['--disable-stylo', 04:22:48 INFO - '--xperf_path', 04:22:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:48 INFO - 'tests': ['tp5n']}}} 04:22:48 INFO - Running post-action listener: _resource_record_post_action 04:22:48 INFO - [mozharness: 2017-09-25 11:22:48.913778Z] Finished populate-webroot step (success) 04:22:48 INFO - [mozharness: 2017-09-25 11:22:48.913885Z] Running create-virtualenv step. 04:22:48 INFO - Running pre-action listener: _resource_record_pre_action 04:22:48 INFO - Running main action method: create_virtualenv 04:22:48 INFO - Creating virtualenv /builds/slave/test/build/venv 04:22:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:22:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:22:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:22:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:22:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:22:49 INFO - Installing distribute.............................................................................................................................................................................................done. 04:22:49 INFO - Installing pip...............done. 04:22:49 INFO - Return code: 0 04:22:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:22:50 INFO - Reading from file tmpfile_stdout 04:22:50 INFO - Output received: 04:22:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:22:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:22:50 INFO - Using env: {'DISPLAY': ':0', 04:22:50 INFO - 'HOME': '/home/cltbld', 04:22:50 INFO - 'LANG': 'en_US.UTF-8', 04:22:50 INFO - 'LANGUAGE': 'en_US:en', 04:22:50 INFO - 'LOGNAME': 'cltbld', 04:22:50 INFO - 'MAIL': '/var/mail/cltbld', 04:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:50 INFO - 'MOZ_NO_REMOTE': '1', 04:22:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:50 INFO - 'NO_EM_RESTART': '1', 04:22:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:50 INFO - 'PWD': '/builds/slave/test', 04:22:50 INFO - 'SHELL': '/bin/bash', 04:22:50 INFO - 'SHLVL': '1', 04:22:50 INFO - 'TERM': 'linux', 04:22:50 INFO - 'TMOUT': '86400', 04:22:50 INFO - 'USER': 'cltbld', 04:22:50 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:50 INFO - '_': '/tools/buildbot/bin/python'} 04:22:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:22:51 INFO - Downloading/unpacking pip>=1.5 04:22:51 INFO - Running setup.py egg_info for package pip 04:22:51 INFO - warning: no files found matching 'pip/cacert.pem' 04:22:51 INFO - warning: no files found matching '*.html' under directory 'docs' 04:22:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:22:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:22:51 INFO - Installing collected packages: pip 04:22:51 INFO - Found existing installation: pip 1.1 04:22:51 INFO - Uninstalling pip: 04:22:51 INFO - Successfully uninstalled pip 04:22:51 INFO - Running setup.py install for pip 04:22:51 INFO - warning: no files found matching 'pip/cacert.pem' 04:22:51 INFO - warning: no files found matching '*.html' under directory 'docs' 04:22:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:22:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:22:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:22:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:22:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:22:51 INFO - Successfully installed pip 04:22:51 INFO - Cleaning up... 04:22:51 INFO - Return code: 0 04:22:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:22:51 INFO - Using env: {'DISPLAY': ':0', 04:22:51 INFO - 'HOME': '/home/cltbld', 04:22:51 INFO - 'LANG': 'en_US.UTF-8', 04:22:51 INFO - 'LANGUAGE': 'en_US:en', 04:22:51 INFO - 'LOGNAME': 'cltbld', 04:22:51 INFO - 'MAIL': '/var/mail/cltbld', 04:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:51 INFO - 'MOZ_NO_REMOTE': '1', 04:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:51 INFO - 'NO_EM_RESTART': '1', 04:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:51 INFO - 'PWD': '/builds/slave/test', 04:22:51 INFO - 'SHELL': '/bin/bash', 04:22:51 INFO - 'SHLVL': '1', 04:22:51 INFO - 'TERM': 'linux', 04:22:51 INFO - 'TMOUT': '86400', 04:22:51 INFO - 'USER': 'cltbld', 04:22:51 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:51 INFO - '_': '/tools/buildbot/bin/python'} 04:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:51 INFO - Downloading/unpacking psutil>=3.1.1 04:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:22:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:52 INFO - Installing collected packages: psutil 04:22:52 INFO - Running setup.py install for psutil 04:22:52 INFO - building 'psutil._psutil_linux' extension 04:22:52 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 04:22: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 04:22:53 INFO - building 'psutil._psutil_posix' extension 04:22:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:22:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:22:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:53 INFO - Successfully installed psutil 04:22:53 INFO - Cleaning up... 04:22:53 INFO - Return code: 0 04:22:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:22:53 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')]} 04:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:53 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')]} 04:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:53 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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22:53 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 04:22:53 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 04:22:53 INFO - Using env: {'DISPLAY': ':0', 04:22:53 INFO - 'HOME': '/home/cltbld', 04:22:53 INFO - 'LANG': 'en_US.UTF-8', 04:22:53 INFO - 'LANGUAGE': 'en_US:en', 04:22:53 INFO - 'LOGNAME': 'cltbld', 04:22:53 INFO - 'MAIL': '/var/mail/cltbld', 04:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:53 INFO - 'MOZ_NO_REMOTE': '1', 04:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:53 INFO - 'NO_EM_RESTART': '1', 04:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:53 INFO - 'PWD': '/builds/slave/test', 04:22:53 INFO - 'SHELL': '/bin/bash', 04:22:53 INFO - 'SHLVL': '1', 04:22:53 INFO - 'TERM': 'linux', 04:22:53 INFO - 'TMOUT': '86400', 04:22:53 INFO - 'USER': 'cltbld', 04:22:53 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:53 INFO - '_': '/tools/buildbot/bin/python'} 04:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:22:54 INFO - Installing collected packages: mozsystemmonitor 04:22:54 INFO - Running setup.py install for mozsystemmonitor 04:22:55 INFO - Successfully installed mozsystemmonitor 04:22:55 INFO - Cleaning up... 04:22:55 INFO - Return code: 0 04:22:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22: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 04:22: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 04:22:55 INFO - Using env: {'DISPLAY': ':0', 04:22:55 INFO - 'HOME': '/home/cltbld', 04:22:55 INFO - 'LANG': 'en_US.UTF-8', 04:22:55 INFO - 'LANGUAGE': 'en_US:en', 04:22:55 INFO - 'LOGNAME': 'cltbld', 04:22:55 INFO - 'MAIL': '/var/mail/cltbld', 04:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:55 INFO - 'MOZ_NO_REMOTE': '1', 04:22:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:55 INFO - 'NO_EM_RESTART': '1', 04:22:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:55 INFO - 'PWD': '/builds/slave/test', 04:22:55 INFO - 'SHELL': '/bin/bash', 04:22:55 INFO - 'SHLVL': '1', 04:22:55 INFO - 'TERM': 'linux', 04:22:55 INFO - 'TMOUT': '86400', 04:22:55 INFO - 'USER': 'cltbld', 04:22:55 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:55 INFO - '_': '/tools/buildbot/bin/python'} 04:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:55 INFO - Downloading/unpacking jsonschema==2.5.1 04:22: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 04:22: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 04:22: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 04:22: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 04:22:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:22:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:22:57 INFO - warning: no files found matching '*.txt' 04:22:57 INFO - no previously-included directories found matching 'build' 04:22:57 INFO - no previously-included directories found matching 'dist' 04:22:57 INFO - no previously-included directories found matching '.git*' 04:22:57 INFO - Installing collected packages: jsonschema, functools32 04:22:57 INFO - Running setup.py install for functools32 04:22:57 INFO - warning: no files found matching '*.txt' 04:22:57 INFO - no previously-included directories found matching 'build' 04:22:57 INFO - no previously-included directories found matching 'dist' 04:22:57 INFO - no previously-included directories found matching '.git*' 04:22:57 INFO - Successfully installed jsonschema functools32 04:22:57 INFO - Cleaning up... 04:22:57 INFO - Return code: 0 04:22:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22: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 04:22: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 04:22:57 INFO - Using env: {'DISPLAY': ':0', 04:22:57 INFO - 'HOME': '/home/cltbld', 04:22:57 INFO - 'LANG': 'en_US.UTF-8', 04:22:57 INFO - 'LANGUAGE': 'en_US:en', 04:22:57 INFO - 'LOGNAME': 'cltbld', 04:22:57 INFO - 'MAIL': '/var/mail/cltbld', 04:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:57 INFO - 'MOZ_NO_REMOTE': '1', 04:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:57 INFO - 'NO_EM_RESTART': '1', 04:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:57 INFO - 'PWD': '/builds/slave/test', 04:22:57 INFO - 'SHELL': '/bin/bash', 04:22:57 INFO - 'SHLVL': '1', 04:22:57 INFO - 'TERM': 'linux', 04:22:57 INFO - 'TMOUT': '86400', 04:22:57 INFO - 'USER': 'cltbld', 04:22:57 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:57 INFO - '_': '/tools/buildbot/bin/python'} 04:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:22:58 INFO - Cleaning up... 04:22:58 INFO - Return code: 0 04:22:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:22: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 04:22: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 04:22:58 INFO - Using env: {'DISPLAY': ':0', 04:22:58 INFO - 'HOME': '/home/cltbld', 04:22:58 INFO - 'LANG': 'en_US.UTF-8', 04:22:58 INFO - 'LANGUAGE': 'en_US:en', 04:22:58 INFO - 'LOGNAME': 'cltbld', 04:22:58 INFO - 'MAIL': '/var/mail/cltbld', 04:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:58 INFO - 'MOZ_NO_REMOTE': '1', 04:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:58 INFO - 'NO_EM_RESTART': '1', 04:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:58 INFO - 'PWD': '/builds/slave/test', 04:22:58 INFO - 'SHELL': '/bin/bash', 04:22:58 INFO - 'SHLVL': '1', 04:22:58 INFO - 'TERM': 'linux', 04:22:58 INFO - 'TMOUT': '86400', 04:22:58 INFO - 'USER': 'cltbld', 04:22:58 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:58 INFO - '_': '/tools/buildbot/bin/python'} 04:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:22: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 04:22: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 04:22: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 04:22: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 04:22:59 INFO - Downloading blobuploader-1.2.4.tar.gz 04:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:22:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:22: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 04:22: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 04:22: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 04:22: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 04:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:00 INFO - Downloading docopt-0.6.1.tar.gz 04:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:00 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:00 INFO - Running setup.py install for blobuploader 04:23:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:00 INFO - Running setup.py install for requests 04:23:00 INFO - Running setup.py install for docopt 04:23:00 INFO - Successfully installed blobuploader requests docopt 04:23:00 INFO - Cleaning up... 04:23:00 INFO - Return code: 0 04:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23: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')]} 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23: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')]} 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23: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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:23: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 04:23: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 04:23:00 INFO - Using env: {'DISPLAY': ':0', 04:23:00 INFO - 'HOME': '/home/cltbld', 04:23:00 INFO - 'LANG': 'en_US.UTF-8', 04:23:00 INFO - 'LANGUAGE': 'en_US:en', 04:23:00 INFO - 'LOGNAME': 'cltbld', 04:23:00 INFO - 'MAIL': '/var/mail/cltbld', 04:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:00 INFO - 'MOZ_NO_REMOTE': '1', 04:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:00 INFO - 'NO_EM_RESTART': '1', 04:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:00 INFO - 'PWD': '/builds/slave/test', 04:23:00 INFO - 'SHELL': '/bin/bash', 04:23:00 INFO - 'SHLVL': '1', 04:23:00 INFO - 'TERM': 'linux', 04:23:00 INFO - 'TMOUT': '86400', 04:23:00 INFO - 'USER': 'cltbld', 04:23:00 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:00 INFO - '_': '/tools/buildbot/bin/python'} 04:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:01 INFO - Running setup.py (path:/tmp/pip-eFgLbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:01 INFO - Running setup.py (path:/tmp/pip-k0VA3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:01 INFO - Running setup.py (path:/tmp/pip-ZC5ISh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:01 INFO - Running setup.py (path:/tmp/pip-2d0eHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:01 INFO - Running setup.py (path:/tmp/pip-iwOJ1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:01 INFO - Running setup.py (path:/tmp/pip-TS0KKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:01 INFO - Running setup.py (path:/tmp/pip-p7YF7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:01 INFO - Running setup.py (path:/tmp/pip-dK25UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:01 INFO - Running setup.py (path:/tmp/pip-_YAPC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:01 INFO - Running setup.py (path:/tmp/pip-PtuM9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:01 INFO - Running setup.py (path:/tmp/pip-JJvxcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:01 INFO - Running setup.py (path:/tmp/pip-Vjmc4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:01 INFO - Running setup.py (path:/tmp/pip-y_aYBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:01 INFO - Running setup.py (path:/tmp/pip-eedD4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:02 INFO - Running setup.py (path:/tmp/pip-13vBxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:02 INFO - Running setup.py (path:/tmp/pip-ZGuDTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:02 INFO - Running setup.py (path:/tmp/pip-uNdT6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:02 INFO - Running setup.py install for manifestparser 04:23:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:02 INFO - Running setup.py install for mozcrash 04:23:02 INFO - Running setup.py install for mozdebug 04:23:02 INFO - Running setup.py install for mozdevice 04:23:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:02 INFO - Running setup.py install for mozfile 04:23:02 INFO - Running setup.py install for mozhttpd 04:23:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:02 INFO - Running setup.py install for mozinfo 04:23:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:02 INFO - Running setup.py install for mozInstall 04:23:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Running setup.py install for mozleak 04:23:03 INFO - Running setup.py install for mozlog 04:23:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Running setup.py install for moznetwork 04:23:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Running setup.py install for mozprocess 04:23:03 INFO - Running setup.py install for mozprofile 04:23:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Running setup.py install for mozrunner 04:23:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Running setup.py install for mozscreenshot 04:23:03 INFO - Running setup.py install for moztest 04:23:03 INFO - Running setup.py install for mozversion 04:23:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:03 INFO - Cleaning up... 04:23:04 INFO - Return code: 0 04:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:23:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:04 INFO - Using env: {'DISPLAY': ':0', 04:23:04 INFO - 'HOME': '/home/cltbld', 04:23:04 INFO - 'LANG': 'en_US.UTF-8', 04:23:04 INFO - 'LANGUAGE': 'en_US:en', 04:23:04 INFO - 'LOGNAME': 'cltbld', 04:23:04 INFO - 'MAIL': '/var/mail/cltbld', 04:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:04 INFO - 'MOZ_NO_REMOTE': '1', 04:23:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:04 INFO - 'NO_EM_RESTART': '1', 04:23:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:04 INFO - 'PWD': '/builds/slave/test', 04:23:04 INFO - 'SHELL': '/bin/bash', 04:23:04 INFO - 'SHLVL': '1', 04:23:04 INFO - 'TERM': 'linux', 04:23:04 INFO - 'TMOUT': '86400', 04:23:04 INFO - 'USER': 'cltbld', 04:23:04 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:04 INFO - '_': '/tools/buildbot/bin/python'} 04:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:04 INFO - Running setup.py (path:/tmp/pip-4JAQXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:04 INFO - Running setup.py (path:/tmp/pip-XF2_Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:04 INFO - Running setup.py (path:/tmp/pip-yAw1LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:04 INFO - Running setup.py (path:/tmp/pip-jwMdGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:04 INFO - Running setup.py (path:/tmp/pip-Nzzynt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:04 INFO - Running setup.py (path:/tmp/pip-tpmulS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:04 INFO - Running setup.py (path:/tmp/pip-uqHHID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:04 INFO - Running setup.py (path:/tmp/pip-sMgmKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:04 INFO - Running setup.py (path:/tmp/pip-XLzCi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:04 INFO - Running setup.py (path:/tmp/pip-TLfvtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23: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)) 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:04 INFO - Running setup.py (path:/tmp/pip-mKKaFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:05 INFO - Running setup.py (path:/tmp/pip-Qiu1T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:05 INFO - Running setup.py (path:/tmp/pip-DgeymQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23: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)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:05 INFO - Running setup.py (path:/tmp/pip-gRVSan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23: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)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:05 INFO - Running setup.py (path:/tmp/pip-RYEOgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23: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)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:05 INFO - Running setup.py (path:/tmp/pip-H5Wq3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23: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)) 04:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:05 INFO - Running setup.py (path:/tmp/pip-LCK3cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23: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 04:23: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 04:23: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 04:23: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 04:23:06 INFO - Downloading blessings-1.6.tar.gz 04:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:06 INFO - Installing collected packages: blessings 04:23:06 INFO - Running setup.py install for blessings 04:23:06 INFO - Successfully installed blessings 04:23:06 INFO - Cleaning up... 04:23:06 INFO - Return code: 0 04:23:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:07 INFO - Reading from file tmpfile_stdout 04:23:07 INFO - Current package versions: 04:23:07 INFO - argparse == 1.2.1 04:23:07 INFO - blessings == 1.6 04:23:07 INFO - blobuploader == 1.2.4 04:23:07 INFO - docopt == 0.6.1 04:23:07 INFO - functools32 == 3.2.3-2 04:23:07 INFO - jsonschema == 2.5.1 04:23:07 INFO - manifestparser == 1.1 04:23:07 INFO - mozInstall == 1.13 04:23:07 INFO - mozcrash == 1.0 04:23:07 INFO - mozdebug == 0.1 04:23:07 INFO - mozdevice == 0.51 04:23:07 INFO - mozfile == 1.2 04:23:07 INFO - mozhttpd == 0.7 04:23:07 INFO - mozinfo == 0.10 04:23:07 INFO - mozleak == 0.1 04:23:07 INFO - mozlog == 3.5 04:23:07 INFO - moznetwork == 0.27 04:23:07 INFO - mozprocess == 0.25 04:23:07 INFO - mozprofile == 0.28 04:23:07 INFO - mozrunner == 6.13 04:23:07 INFO - mozscreenshot == 0.1 04:23:07 INFO - mozsystemmonitor == 0.3 04:23:07 INFO - moztest == 0.8 04:23:07 INFO - mozversion == 1.4 04:23:07 INFO - psutil == 3.1.1 04:23:07 INFO - requests == 1.2.3 04:23:07 INFO - wsgiref == 0.1.2 04:23:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:07 INFO - Using env: {'DISPLAY': ':0', 04:23:07 INFO - 'HOME': '/home/cltbld', 04:23:07 INFO - 'LANG': 'en_US.UTF-8', 04:23:07 INFO - 'LANGUAGE': 'en_US:en', 04:23:07 INFO - 'LOGNAME': 'cltbld', 04:23:07 INFO - 'MAIL': '/var/mail/cltbld', 04:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:07 INFO - 'MOZ_NO_REMOTE': '1', 04:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:07 INFO - 'NO_EM_RESTART': '1', 04:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:07 INFO - 'PWD': '/builds/slave/test', 04:23:07 INFO - 'SHELL': '/bin/bash', 04:23:07 INFO - 'SHLVL': '1', 04:23:07 INFO - 'TERM': 'linux', 04:23:07 INFO - 'TMOUT': '86400', 04:23:07 INFO - 'USER': 'cltbld', 04:23:07 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:07 INFO - '_': '/tools/buildbot/bin/python'} 04:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23: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)) 04:23:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:23: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 04:23: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 04:23: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 04:23: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 04:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:23: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)) 04:23: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)) 04:23:08 INFO - Installing collected packages: simplejson 04:23:08 INFO - Running setup.py install for simplejson 04:23:08 INFO - building 'simplejson._speedups' extension 04:23: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 04:23: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 04:23:09 INFO - Successfully installed simplejson 04:23:09 INFO - Cleaning up... 04:23:09 INFO - Return code: 0 04:23:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23: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')]} 04:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23: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')]} 04:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23: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 0x1d211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x208e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', '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 04:23: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 04:23: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 04:23:09 INFO - Using env: {'DISPLAY': ':0', 04:23:09 INFO - 'HOME': '/home/cltbld', 04:23:09 INFO - 'LANG': 'en_US.UTF-8', 04:23:09 INFO - 'LANGUAGE': 'en_US:en', 04:23:09 INFO - 'LOGNAME': 'cltbld', 04:23:09 INFO - 'MAIL': '/var/mail/cltbld', 04:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:09 INFO - 'MOZ_NO_REMOTE': '1', 04:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:09 INFO - 'NO_EM_RESTART': '1', 04:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:09 INFO - 'PWD': '/builds/slave/test', 04:23:09 INFO - 'SHELL': '/bin/bash', 04:23:09 INFO - 'SHLVL': '1', 04:23:09 INFO - 'TERM': 'linux', 04:23:09 INFO - 'TMOUT': '86400', 04:23:09 INFO - 'USER': 'cltbld', 04:23:09 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:09 INFO - '_': '/tools/buildbot/bin/python'} 04:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:23:09 INFO - Cleaning up... 04:23:09 INFO - Return code: 0 04:23:09 INFO - Running post-action listener: _resource_record_post_action 04:23:09 INFO - Running post-action listener: _start_resource_monitoring 04:23:09 INFO - Starting resource monitoring. 04:23:09 INFO - [mozharness: 2017-09-25 11:23:09.751038Z] Finished create-virtualenv step (success) 04:23:09 INFO - [mozharness: 2017-09-25 11:23:09.751554Z] Running install step. 04:23:09 INFO - Running pre-action listener: _resource_record_pre_action 04:23:09 INFO - Running main action method: install 04:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:09 INFO - Reading from file tmpfile_stdout 04:23:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:10 INFO - Reading from file tmpfile_stdout 04:23:10 INFO - Output received: 04:23:10 INFO - Usage: mozinstall [options] installer 04:23:10 INFO - Options: 04:23:10 INFO - -h, --help show this help message and exit 04:23:10 INFO - -d DEST, --destination=DEST 04:23:10 INFO - Directory to install application into. [default: 04:23:10 INFO - "/builds/slave/test"] 04:23:10 INFO - --app=APP Application being installed. [default: firefox] 04:23:10 INFO - mkdir: /builds/slave/test/build/application 04:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:23:28 INFO - Reading from file tmpfile_stdout 04:23:28 INFO - Output received: 04:23:28 INFO - /builds/slave/test/build/application/firefox/firefox 04:23:28 INFO - Running post-action listener: _resource_record_post_action 04:23:28 INFO - [mozharness: 2017-09-25 11:23:28.038676Z] Finished install step (success) 04:23:28 INFO - [mozharness: 2017-09-25 11:23:28.038929Z] Running setup-mitmproxy step. 04:23:28 INFO - Running pre-action listener: _resource_record_pre_action 04:23:28 INFO - Running main action method: setup_mitmproxy 04:23:28 INFO - Skipping: mitmproxy is not required 04:23:28 INFO - Running post-action listener: _resource_record_post_action 04:23:28 INFO - [mozharness: 2017-09-25 11:23:28.040060Z] Finished setup-mitmproxy step (success) 04:23:28 INFO - [mozharness: 2017-09-25 11:23:28.040280Z] Running run-tests step. 04:23:28 INFO - Running pre-action listener: _resource_record_pre_action 04:23:28 INFO - Running pre-action listener: _set_gcov_prefix 04:23:28 INFO - Running main action method: run_tests 04:23:28 WARNING - Try message not found. 04:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:23:28 INFO - Python 2.7.3 04:23:28 INFO - Return code: 0 04:23:28 INFO - grabbing minidump binary from tooltool 04:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208bd60>, '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 0x208ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x208cef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:23:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:23:28 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:23:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:23:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:23:28 INFO - Return code: 0 04:23:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:23:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:23:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:23:28 INFO - ENV: RUST_BACKTRACE is now full 04:23:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:23:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 04:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --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 04:23:28 INFO - Using env: {'DISPLAY': ':0', 04:23:28 INFO - 'HOME': '/home/cltbld', 04:23:28 INFO - 'LANG': 'en_US.UTF-8', 04:23:28 INFO - 'LANGUAGE': 'en_US:en', 04:23:28 INFO - 'LOGNAME': 'cltbld', 04:23:28 INFO - 'MAIL': '/var/mail/cltbld', 04:23:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:28 INFO - 'MOZ_NO_REMOTE': '1', 04:23:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:28 INFO - 'NO_EM_RESTART': '1', 04:23:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:28 INFO - 'PWD': '/builds/slave/test', 04:23:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:28 INFO - 'RUST_BACKTRACE': 'full', 04:23:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:23:28 INFO - 'SHELL': '/bin/bash', 04:23:28 INFO - 'SHLVL': '1', 04:23:28 INFO - 'TERM': 'linux', 04:23:28 INFO - 'TMOUT': '86400', 04:23:28 INFO - 'USER': 'cltbld', 04:23:28 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:28 INFO - '_': '/tools/buildbot/bin/python'} 04:23:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 04:23:28 INFO - mozversion application_buildid: 20170925095842 04:23:28 INFO - mozversion application_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 04:23:28 INFO - mozversion application_display_name: Nightly 04:23:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:23:28 INFO - mozversion application_name: Firefox 04:23:28 INFO - mozversion application_remotingname: firefox 04:23:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:23:28 INFO - mozversion application_vendor: Mozilla 04:23:28 INFO - mozversion application_version: 58.0a1 04:23:28 INFO - mozversion platform_buildid: 20170925095842 04:23:28 INFO - mozversion platform_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 04:23:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:23:28 INFO - mozversion platform_version: 58.0a1 04:23:28 INFO - using testdate: 1506338608 04:23:28 INFO - actual date: 1506338608 04:23:28 INFO - starting webserver on 'localhost:36984' 04:23:28 INFO - SUITE-START | Running 2 tests 04:23:28 INFO - TEST-START | dromaeo_css 04:23:28 INFO - Initialising browser for dromaeo_css test... 04:23:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36984/getInfo.html -profile /tmp/tmpxeweHy/profile 04:23:28 INFO - TEST-INFO | started process 19916 (/builds/slave/test/build/application/firefox/firefox http://localhost:36984/getInfo.html) 04:23:34 INFO - TEST-INFO | 19916: exit 0 04:23:34 INFO - Browser initialized. 04:23:34 INFO - Running cycle 1/1 for dromaeo_css test... 04:23:34 INFO - Using env: {'DISPLAY': ':0', 04:23:34 INFO - 'HOME': '/home/cltbld', 04:23:34 INFO - 'JSGC_DISABLE_POISONING': '1', 04:23:34 INFO - 'LANG': 'en_US.UTF-8', 04:23:34 INFO - 'LANGUAGE': 'en_US:en', 04:23:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:23:34 INFO - 'LOGNAME': 'cltbld', 04:23:34 INFO - 'MAIL': '/var/mail/cltbld', 04:23:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:34 INFO - 'MOZ_CRASHREPORTER': '1', 04:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:23:34 INFO - 'MOZ_NO_REMOTE': '1', 04:23:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:34 INFO - 'NO_EM_RESTART': '1', 04:23:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:34 INFO - 'PWD': '/builds/slave/test', 04:23:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:34 INFO - 'RUST_BACKTRACE': 'full', 04:23:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:23:34 INFO - 'SHELL': '/bin/bash', 04:23:34 INFO - 'SHLVL': '1', 04:23:34 INFO - 'TERM': 'linux', 04:23:34 INFO - 'TMOUT': '86400', 04:23:34 INFO - 'USER': 'cltbld', 04:23:34 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:34 INFO - '_': '/tools/buildbot/bin/python'} 04:23:34 INFO - TEST-INFO | started process 20157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxeweHy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:23:35 INFO - PID 20157 | 04:23:35 INFO - PID 20157 | (/builds/slave/test/build/application/firefox/firefox:20207): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:23:35 INFO - PID 20157 | 04:23:45 INFO - PID 20157 | RSS: Main: 144433152 04:23:45 INFO - PID 20157 | 04:26:42 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36984/tests/dromaeo/cssquery-ext.html) 04:26:43 INFO - PID 20157 | RSS: Main: 146149376 04:26:43 INFO - PID 20157 | 04:29:44 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-ext.html (next: http://localhost:36984/tests/dromaeo/cssquery-jquery.html) 04:29:45 INFO - PID 20157 | RSS: Main: 146202624 04:29:45 INFO - PID 20157 | 04:32:46 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36984/tests/dromaeo/cssquery-mootools.html) 04:32:47 INFO - PID 20157 | RSS: Main: 148262912 04:32:47 INFO - PID 20157 | 04:35:49 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36984/tests/dromaeo/cssquery-prototype.html) 04:35:49 INFO - PID 20157 | RSS: Main: 146599936 04:35:49 INFO - PID 20157 | 04:38:52 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36984/tests/dromaeo/cssquery-yui.html) 04:38:53 INFO - PID 20157 | RSS: Main: 147877888 04:38:53 INFO - PID 20157 | 04:41:54 INFO - PID 20157 | Cycle 1(1): loaded http://localhost:36984/tests/dromaeo/cssquery-yui.html (next: http://localhost:36984/tests/dromaeo/cssquery-dojo.html) 04:41:55 INFO - PID 20157 | RSS: Main: 148422656 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | __start_tp_report 04:41:55 INFO - PID 20157 | _x_x_mozilla_page_load 04:41:55 INFO - PID 20157 | _x_x_mozilla_page_load_details 04:41:55 INFO - PID 20157 | |i|pagename|runs| 04:41:55 INFO - PID 20157 | |0;dojo.html;3903.9024024399387;3910.296146693594;3910.569837317893;3932.4887764590617;3952.2688302664;6151.046587778901;6188.659623720693;6263.843403914893;6270.84322891928;6344.39728225819;6251.062340648905;6293.779717709881;6306.148669929558;6323.74705011799;6349.142865713139;5596.4403559643915;5692.5161361284345;5699.829005129853;5706.572007099463;5709.05800542912;245.72626431096774;5636.464535869106;5641.210230567724;5703.03048481757;5717.228174196493;5258.106121959257;5549.722513874331;5623.690697011662;5635.380108188096;5688.033034384144;5565.026120428909;5636.15457681347;5636.2391077204875;5640.605157638967;5641.238432811559;5655.321361436654;5664.858378540528;5666.801661941812;5670.943290567083;5671.2060311556415;6126.295476020226;6199.690015499252;6293.056041593752;6312.842178945517;6342.682865856688;6777.830554236134;6779.186497620268;6801.21785994612;6860.03959445678;6895.379415852597;6860.279670634612;6879.174499060145;6922.7923162305215;6950.652467376611;6977.860442791167;6221.688915554249;6306.1171435998995;6392.808215753535;6403.519736019817;6425.903611445879;6936.79189624312;7025.332593403618;7043.92956070443;7097.89353159703;7099.467539934422;6725.428338591177;6746.123004009447;6758.357955994173;6762.222344430355;6765.2896445872475;6839.0425340452375;6841.452683785285;6858.622775747282;6859.759908403182;6862.279460656659;6766.830829229309;6837.589744615339;6845.726170953206;6877.65611719412;6877.89683154753;6775.356341147583;6789.422899053537;6801.9319806801295;6881.311868813079;6882.655867206619;4951.90096198074;4986.675866068694;5035.723035233024;5054.620903432257;5062.493750624982;4566.474855391606;4604.700694454917;4617.699849509771;4644.16405047088;4659.510751371125;6157.538184636171;6181.1655426516845;6257.843553911189;6275.592086514362;6277.027060805583;6691.1636045494315;6804.455643548505;6810.523263371616;6879.690413931385;6886.655667216619;6153.446189842935;6189.288231853305;6198.659073750897;6204.7828326008375;6207.286162091327;5505.697186654692;5533.03171944908;5535.197396377584;5535.25274087993;5536.335639723178;6930.61881596517;6961.338672826073;7011.1586609607175;7023.613701246481;7038.894416583756;6749.392554670103;6772.93227067723;6826.93173068263;6858.211305699909;6902.206246281541;7051.788446346618;7052.118485189351;7057.929420705727;7150.821229469305;7165.104361954755;6632.602043877383;6733.6296503692765;6743.1908171019795;6843.7604683835825;6850.691718872762;6887.139107611549;6909;6915.239323674492;6924.272951340238;6929.7228110875;6593.538452308292;6610.8016759497295;6676.966615167087;6680.665966701743;6684.766033188815;6503.284638689645;6571.3100124488155;6576.8355791103695;6669.2997235289595;6670.633115178712;6468.191476065492;6483.481321494175;6567.9671601641685;6572.507061970276;6576.934230657824;6567.343265673395;6670.7665231716655;6671.733130674718;6717.429018533284;6742.595444273359;6418.133551970424;6426.228852537725;6439.549231553934;6509.316521765147;6538.1500404947055 04:41:55 INFO - PID 20157 | |1;ext.html;901.2339511415298;902.4855832175663;918.5453200665671;956.7655924298548;957.2150836314225;42488.57511424885;43033.13933721324;43066.35400468996;43483.56516434834;43558.56441435589;40337.19325613486;42916;43970.78014609931;43982;44095;8396.286315663176;8531.74404767858;8536.231739143475;8539.74380768578;8549.230569248766;8355.83288334233;8535.615897284637;8544.359173062014;8544.829103417958;8547.700830470934;8439.662413503453;8546;8546.658133674677;8559.871601925975;8578;8462.19609137131;8477.915220847774;8519.53142577158;8532.488050716976;8532.829343413128;8302.958485207595;8332.916670833336;8347.123552027011;8347.415680902339;8403.453775504573;38564.228715425816;38819.61180388188;38843.611563884566;38847.8057609713;40125.197496049914;3572.589152247499;3592.1558433768046;3600.279944011182;3601.333753255656;3608.1520842601967;32532.349353013044;32623.673763262537;32762.508562371582;32806.50790238148;32857.50713739296;5354.875476150013;5455.863603409946;5489.149181876815;5509.256250406184;5607.719614019283;210872;211323;211650;212121;212780;51380;51774;51907.74046129745;51950;52535;55289.72355138198;55320.17019744707;55352.16971745427;55420.445795542335;55499.16751248734;15176.620584485476;15254.313555890012;15280.083195008336;15293.770593441108;15411.229438528253;12277.649458559552;12370.381480925918;12375.443105060294;12383.938080309721;12410.379481026093;16395.836041639668;16463.34146634145;16574.83976121684;16693.248803803857;16698.6660266794;6146.754129834846;6210.223722034746;6306.211723534559;6349.206349206349;6352.555321127569;1082.9079528240031;1095.6822521468844;1097.7145942055124;1104.5581767293147;1105.5909313553875;26091.08681196149;26371.340716482242;26414.6037809433;26416.339591509983;26502;1179.262960649594;1200.2678366196612;1201.591458903977;1219.8292239086536;1226.9938650306692;13744.106633068819;13826.585202443943;13870.930645346709;14136.85863141376;14152.29238538069;320155;322027;323143;323207;323510;51028.74485627622;51409.74295128501;56384.15423768647;56923.43076569181;59430;1517.2413793103449;1535.3551508366513;1536.5928029072304;1538.5538193923637;1543.53693891832;30819.537706933967;30845.766169353872;31052;31359.216019598778;31482.37035259328;37118.814405928875;41186.38220426636;41258.174836502505;41319.7934010328;41368.37947430731;257309;257315;257417;257740;257820;38683.80658096691;38692.80653596714;38900.80549597347;39137.80431097826;39199;46558.53441465542;47495.76252118717;47723;48358;48364.27453588128;33648.32703345968;34782.130446738025;35105;35426;35715.82142089273;34328.59699821089;34335.65664343325;34536.30927381388;34639.4804077934;35126.29747405089;40037.39943900786;40523.18953620852;40583.1883362337;40701.389479157246;40745.79627101846;30164.396712065198;30656.540151897294;30784.53823192699;30794;31035.224119397197;25286.74713252844;25519.744802551737;26159.346016349744;26317.73682263153;26413.33966650849 04:41:55 INFO - PID 20157 | |2;jquery.html;607.3471018654947;607.4532920371665;612.9662868542231;621.9782307619234;633.1642232253425;22593.887030564845;23258;23268.883655581718;23276.301710948657;23283;21084.472888177803;22331.55336893265;22525.211617593388;22547.887260563675;22549;6460.870782584358;6538.1827271591055;6540;6601.438877695402;6615.669216539178;6596.241432235282;6660.167479065117;6667.8333041674105;6912.516123871331;6966.4078553323025;6928.515003949726;6929.826754331131;6933.375996160345;6937.930620693779;6939.20199177096;6849.7602583909575;6872.140982377203;6882.621455819928;6901.102856628606;6916.204636466795;6419.069234960951;6420.229572451336;6445.097686323918;6459.224893012869;6464.93535064648;29729.25676858074;31001.37997240065;31329.8433507831;31487.37025259505;31499.527507087412;2215.501512159767;2223.644216925284;2243;2243.7868402501736;2247.5841969235744;20616.896915515477;21334.893325533427;21342.679859802116;21349.679754803692;21350.252741153985;2495.862727549984;2560.244727805302;2610.2821724026044;2633.9473210535875;2657.7608015278524;142737;148872;155382;160838;160956.3904360953;16134.273957671932;16606.418775342805;16854.915725421295;17352.219150138273;17732.20205090774;26372.208833735018;26385.868070659522;26456.206313810617;26554.734452655226;26628.06801761929;10015.649452269136;10208.693739187836;10261.640842570474;10285.9485702571;10329.9483502582;9117.863232051524;9135.908640913638;9189.356745027784;9227.938787039577;9324.720258392259;9565.473898935486;9816.558254878548;9832.164266037467;10157.339772914886;10194.337368071052;2977.7766667500023;3005.5040918248683;3010.9397812043635;3037.544368344722;3119.844007799601;1040.7450174707244;1052.347544522401;1069.7593041565742;1080.4327727942737;1091.476091476096;21205.469863253544;21261.255856044965;21636.134554617638;21768.673469897964;21896;1106.6403418888733;1117.279354816139;1119.261287550213;1120.38938778366;1124.1007194244735;11602.129840262014;11872.940635296769;11889;11928.46321915516;12016.759664806656;556812;572252;582208;599296;600509;123990;125717;128683.35658321835;131626;132071;1084.4631907206142;1090.820014697566;1122.4443900269255;1124.4546394998583;1130.6494986554194;18070.277188912823;18141.81858181454;18188.454346369632;18384.44846654549;18481.445556633324;61463;61542.69228653828;62610.06084908821;62789.68605156945;63488.36511634824;81510.18489815026;81742.59128704318;81931.18068819235;82027.58986205031;82750.58624706838;14985.325660345092;15001.6999660004;15080.321385537896;15107.622309442351;15107.773383399039;22395.888020560444;22628.773712262668;22799.772002279766;23189.188378406674;23206.883965580066;18914;18975.715364269272;18981.43055708331;19101.713474298176;19252.711209332152;16315.755263670817;16703.749443758596;16877.831221687626;16986.830131698523;17247.827521725125;18829.90585047066;19157.80842191616;19222.231110755412;19231.7499362541;19607.60784784325;14616.853831461975;14687.41250349974;14829;14844.851551484348;14870.702585948437;18648.160832762285;18784.7182292263;19043.90478047656;19044.523886902938;19076.713849292548 04:41:55 INFO - PID 20157 | |3;mootools.html;1420.0911416693318;1532.954011379659;1538.4769178479314;1542.4138827245645;1542.6529030968034;18006.549836254082;18690;18717.438476845717;18753;18757.531061723465;17922.103894805274;17941.372051978182;18032.368867089655;18057.27770889163;18084.457466276;2725.509408306504;2833.9291517712018;2834.8440835754027;2835.2770043638843;2836.4610723962383;2777.527820270559;2803.032953631004;2813.127930341601;2813.957790633142;2815.155453363993;2710.214037929008;2751.284665986848;2837.574363845419;2857.9142625721156;2862.9570556441568;2799.7760179185716;2848.316404063031;2850.757685596727;2870.583765354033;2872.971270287291;2650.3771613670774;2704.6619172603423;2737.8220415673118;2748.532749432601;2749.0653177919603;16942.915285423616;17098.829011709848;17113.22990465432;17123.74314385285;17132.40036598713;7681.692732290701;7963.840723185562;7980.920190798076;7993.280604745601;7995.120536740954;5570.601487367062;5682.090865461548;5822.301323841124;5850.531957443394;5853.912191317134;2057.5884823035303;2120.830333573326;2145.8605190662556;2200.713907192071;2224.822014238857;31269.218269543217;31500.054998350086;31508.99999999977;31552;31604.36791264185;7014.123234595676;7096.92903070973;7262.527935684164;7325.706971721178;7381.741639042715;20249.595008099757;20335.593288134154;20391.79608203899;20596.691049634268;20600;7578.204288549733;7716.691332346643;7724.6523906424345;7725.922740772633;7727.1886451922865;6880.277570855088;6963.9651801740665;7070.197099739534;7243.167035790952;7390.076240469942;5663.688497132614;5827.271591051119;5846.766129354864;5872.354041055481;5993.670348130808;1693.119577819527;1708.6838934797102;1724.6895558799536;1735.1064201936013;1757.1741281597629;847.7456762971084;862.6764963138822;873.1137895036543;881.6341218394423;882.6116508736136;8757.211850933447;9702.320837541305;9741.756456088655;9765.951170244103;10018.64934727281;781.9100803407681;784.8077221080757;786.080286065305;798.6685525506966;799.0371602219312;5486.039943009957;5514.91727624086;5631.493165615114;6082.878342433127;6307.432331090224;24341.878290608787;26211;26295.474090518084;26312;26403.339916502242;24991.25865393284;25406.364840879127;25930.870345648527;26104.34739131537;26167.214983550522;11393.202475826778;11954.940225298991;11967.042636589053;11970.82043769344;11980;9540.093691099335;9664.323497355399;9720.125188733047;9777.706668799663;9827.31208815376;20796.27213047536;21148.259810906544;21152.259670911444;21421.892890535448;21898.67151992753;22237.444063898532;22272.665910011037;22338.664920025887;22356.441088972257;22358.105675772786;12117.636470905887;12427.254364738144;12444.81332779991;12451.813222801484;12486.250824950532;15105.622359441102;15122.924385378443;15242.466513671969;15280.847191528386;15298.923505382401;11721.238119522204;11820.468078936297;11947.283163010246;12056.51773929033;12250.816237756262;11029.61396351156;11279.605213817476;11339.376334301693;11520.884791152317;11698.181127321006;9942.950285248528;10115.39307641544;10196.082352588302;10201.234907381828;10206.132478739093;7979.880301795361;8116.472429292077;8166.918330816616;8194.262516373556;8235.588220588827;8103.675852966051;8161.632726527439;8188.2630563249595;8190.959045204736;8258.132896045829 04:41:55 INFO - PID 20157 | |4;prototype.html;541.9403865574786;578.69907648023;580.5152697497589;585.4818485640209;592.3898384663797;6262.812115636527;6289.276733175686;6307.747690092397;6315.084312774645;6333.303336632966;6126.295476020259;6128.172696685946;6136.631802091878;6148.169997050397;6169.334279744467;4773.474917759044;4975.477574854643;4996.750162491879;5022.07091688038;5053.418856831457;4857.902841943159;4890.535399137077;4898.51014898509;4925.211966085427;4936;4839.298301746245;4845.466998630148;4868.732219727914;4871.975640121795;4908.263760435928;4868.63485238605;4878.268169824874;4907.7546122694075;4915.459299477036;4920.876978075541;4487.461504619434;4507.323901414814;4521.932171017437;4531.462381066581;4558.772061396917;6078.209832721718;6104.786332478387;6125.418085281891;6149.354317796657;6214;5730;5751.453611906904;5758.654480731169;5785.855353616151;5803.7098145093;6525.543211975164;6569.277379488252;6580.309067547886;6599.436304395621;6611.338866113399;4679.859604211879;5020.121478741242;5043.67216130954;5047.425812835444;5052.494750524918;6701.352965578569;6725.966370168167;6760.8309792255095;6769;6936.341047600419;5505.779768809202;5566.192902029264;5599.972000139973;5601.6358936668985;5617.096118197814;5637.56242158247;5645.774169033192;5648.265725455664;5695.231143795618;5708.31500219968;6009.369016253318;6017.548683848641;6040.939590604125;6067.908981365283;6083.11794789753;6590.505712071614;6633.86732265352;6656.933430665728;6684.63234522106;6688.799336019927;1916.6550020996076;1919.5680971781237;1930.1700268884233;1940.223910435837;1954.355062829263;1436.992815035918;1493.5071426429251;1502.3239542206052;1506.879449644026;1517.840626734199;5878.177055212273;5961.463468287875;5971.970140149271;5982.341942386334;5982.880342393128;6461.741530338733;6512.772052978123;6542.443892269211;6578.342165783383;6584.176977877765;5959.0763431668165;5969.522438204933;5999.370066142992;6003.369646187175;6009.1587177795145;3460.740444466675;3469.61834198238;3477.9913824990467;3512.8946131616094;3549.343371476285;6766.424853887376;6855.298139004981;6971.302869713089;6990.370866622028;7020.262872398427;6779.610194902549;6808.446832437836;6809.489288303397;6833.829154271084;6876.69054892531;6803.353681400259;6856.24581296047;6866.759663411856;6959.4432445404245;6997.335253150943;6558.442532384801;6568.441682456853;6609.3060228675295;6613.27254002069;6624.867502650017;6535.509836762357;6536.8365790855605;6578.309277525982;6630.248446894678;6653.9667301663185;6904.378605925491;6919.688614012281;6953.4089602384365;7007.439404847702;7009.369156775914;5551.139573366135;5578.246936663661;5622.493975542221;5627.831165065055;5646.830595082154;6288.057785392943;6514.120593719977;6522.706478208397;6580.696756135506;6674.098996635586;6170.629762214281;6336.17629708135;6382.776602818879;6465.321141280286;6519.7392104315295;6291.905621415774;6333.58831675958;6377.553571249968;6415.807525774234;6416.197975253093;6208.9689551551955;6269;6324.525660575568;6416.454601358937;6423;6288.528360373082;6425.10048593188;6451;6452.290248072803;6527.771527996498;6214.844628884314;6249.812505624838;6300.71646775887;6322.830276398881;6383 04:41:55 INFO - PID 20157 | |5;yui.html;941.3410612571201;950.576993238009;975.3172779054659;986.6497393425334;991.0782971836192;17243.48269551914;17707.64584708305;17783;17785.644287114264;17824.376146834846;16162.2726977286;16670.083145427026;16695.415660451887;16724.83275167248;16856.157192140407;763.0766772205624;769.9653515591784;770.6955752477768;771.0208035794537;771.7839005078587;1029.8867124616286;1031.881333646629;1035.1098055672117;1035.29082578434;1036.5905467340397;1044.8693913260843;1090.1224514266012;1090.2095980414176;1090.7872964771873;1090.8963648453423;1074.7742973975435;1075.9408232547169;1076.1874784537702;1080.114306268852;1081.145894743152;765.9693612255559;789.9328557072656;791.7505985614513;792.5324058805274;793.3573805217734;17561.824381756276;17564;17701.38045168413;17746.645067098714;17763.555911102198;20173.092210850547;20318.593628127503;20434.897825510925;20851.58296834055;21007.47481312964;16915.238814253265;17007.744883826752;17018.914905425518;17110.828891711048;17124.828751712448;3519.419295816187;3536.9469457958153;3539.0975301298263;3541.7520773545602;3546.5212196353677;162148;163920;168829;169008;169493;16239.269232884548;16720.58198545022;16763.1618419081;16765;16802;31813.68186318107;32631;32675.509867352008;32691.346173076407;32754;8852.778680532909;8917.375783695079;8962.910370896208;9064.773380665536;9084.18242358191;4221.810018549173;4253.893652658709;4275.48694156697;4281.892952676208;4303.4190384298345;5828.941710582924;5854.590178687451;5899.262592175978;5933.6736479493175;5953.642781433128;1012.9645462408928;1013.8479228115841;1024.0680980307884;1029.0481304793036;1045.9110975567162;679.2188982669969;680.9897851532231;694.9409300209438;703.1562125449481;707.1549498349526;11587.88412115868;11875.524979000744;12026;12037.518499260106;12452.750944981231;726.4950859152943;728.617475825196;732.4506620034974;737.4337306174542;742;5577.0797818359515;5600.775968961278;5603.80386686464;5638.548916086703;5673.971630141823;147022.26488867486;151599;152227;152471;154007;127781.3610931958;128496;128808.3559582196;131394;135400;107430;108375;109260;110313;113639;12738.808917866054;13275.535356262484;13445.798313025116;13583;13589.320533973065;51034.74482627563;51201.487985119675;52737.20894186514;53737.731311343196;54257;88936.555317223;91494.54252728693;95317.52341238249;99040.50479747556;101180;20060.59878802445;20174.69737953961;20191.899040504704;20230.696539551624;20447.693284600446;20707.79292207059;20982.47543811417;21205.15179392807;22053.33839984803;22082.88958555197;18244.543886402946;18600.162992665093;18894.433167005012;19593.70609440831;20228.59542809165;18033.819661803213;18160.818391815912;18215.544611384823;18275.634487310446;18339.908300458414;17920.372786952394;18571;18911.054447277307;19249.037548122258;19478.220871164995;13576.660583485493;13662.385192666155;13832.930835345758;14054.508092217133;14136.363863626368;13263.270520121487;13716.794248086087;14552.85447145515;14776.704465910836;14842.554723358317 04:41:55 INFO - PID 20157 | __end_tp_report 04:41:55 INFO - PID 20157 | __start_cc_report 04:41:55 INFO - PID 20157 | _x_x_mozilla_cycle_collect,267 04:41:55 INFO - PID 20157 | __end_cc_report 04:41:55 INFO - PID 20157 | __startTimestamp1506339715192__endTimestamp 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | ------- Summary: start ------- 04:41:55 INFO - PID 20157 | Number of tests: 6 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#0] dojo.html Cycles:165 Average:6246.83 Median:6538.15 stddev:875.63 (13.4%) stddev-sans-first:858.81 04:41:55 INFO - PID 20157 | Values: 3903.9 3910.3 3910.6 3932.5 3952.3 6151.0 6188.7 6263.8 6270.8 6344.4 6251.1 6293.8 6306.1 6323.7 6349.1 5596.4 5692.5 5699.8 5706.6 5709.1 245.7 5636.5 5641.2 5703.0 5717.2 5258.1 5549.7 5623.7 5635.4 5688.0 5565.0 5636.2 5636.2 5640.6 5641.2 5655.3 5664.9 5666.8 5670.9 5671.2 6126.3 6199.7 6293.1 6312.8 6342.7 6777.8 6779.2 6801.2 6860.0 6895.4 6860.3 6879.2 6922.8 6950.7 6977.9 6221.7 6306.1 6392.8 6403.5 6425.9 6936.8 7025.3 7043.9 7097.9 7099.5 6725.4 6746.1 6758.4 6762.2 6765.3 6839.0 6841.5 6858.6 6859.8 6862.3 6766.8 6837.6 6845.7 6877.7 6877.9 6775.4 6789.4 6801.9 6881.3 6882.7 4951.9 4986.7 5035.7 5054.6 5062.5 4566.5 4604.7 4617.7 4644.2 4659.5 6157.5 6181.2 6257.8 6275.6 6277.0 6691.2 6804.5 6810.5 6879.7 6886.7 6153.4 6189.3 6198.7 6204.8 6207.3 5505.7 5533.0 5535.2 5535.3 5536.3 6930.6 6961.3 7011.2 7023.6 7038.9 6749.4 6772.9 6826.9 6858.2 6902.2 7051.8 7052.1 7057.9 7150.8 7165.1 6632.6 6733.6 6743.2 6843.8 6850.7 6887.1 6909.0 6915.2 6924.3 6929.7 6593.5 6610.8 6677.0 6680.7 6684.8 6503.3 6571.3 6576.8 6669.3 6670.6 6468.2 6483.5 6568.0 6572.5 6576.9 6567.3 6670.8 6671.7 6717.4 6742.6 6418.1 6426.2 6439.5 6509.3 6538.2 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#1] ext.html Cycles:180 Average:43995.40 Median:37841.52 stddev:69837.06 (184.6%) stddev-sans-first:69958.01 04:41:55 INFO - PID 20157 | Values: 901.2 902.5 918.5 956.8 957.2 42488.6 43033.1 43066.4 43483.6 43558.6 40337.2 42916.0 43970.8 43982.0 44095.0 8396.3 8531.7 8536.2 8539.7 8549.2 8355.8 8535.6 8544.4 8544.8 8547.7 8439.7 8546.0 8546.7 8559.9 8578.0 8462.2 8477.9 8519.5 8532.5 8532.8 8303.0 8332.9 8347.1 8347.4 8403.5 38564.2 38819.6 38843.6 38847.8 40125.2 3572.6 3592.2 3600.3 3601.3 3608.2 32532.3 32623.7 32762.5 32806.5 32857.5 5354.9 5455.9 5489.1 5509.3 5607.7 210872.0 211323.0 211650.0 212121.0 212780.0 51380.0 51774.0 51907.7 51950.0 52535.0 55289.7 55320.2 55352.2 55420.4 55499.2 15176.6 15254.3 15280.1 15293.8 15411.2 12277.6 12370.4 12375.4 12383.9 12410.4 16395.8 16463.3 16574.8 16693.2 16698.7 6146.8 6210.2 6306.2 6349.2 6352.6 1082.9 1095.7 1097.7 1104.6 1105.6 26091.1 26371.3 26414.6 26416.3 26502.0 1179.3 1200.3 1201.6 1219.8 1227.0 13744.1 13826.6 13870.9 14136.9 14152.3 320155.0 322027.0 323143.0 323207.0 323510.0 51028.7 51409.7 56384.2 56923.4 59430.0 1517.2 1535.4 1536.6 1538.6 1543.5 30819.5 30845.8 31052.0 31359.2 31482.4 37118.8 41186.4 41258.2 41319.8 41368.4 257309.0 257315.0 257417.0 257740.0 257820.0 38683.8 38692.8 38900.8 39137.8 39199.0 46558.5 47495.8 47723.0 48358.0 48364.3 33648.3 34782.1 35105.0 35426.0 35715.8 34328.6 34335.7 34536.3 34639.5 35126.3 40037.4 40523.2 40583.2 40701.4 40745.8 30164.4 30656.5 30784.5 30794.0 31035.2 25286.7 25519.7 26159.3 26317.7 26413.3 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#2] jquery.html Cycles:180 Average:38965.04 Median:22363.72 stddev:97831.68 (437.5%) stddev-sans-first:98063.73 04:41:55 INFO - PID 20157 | Values: 607.3 607.5 613.0 622.0 633.2 22593.9 23258.0 23268.9 23276.3 23283.0 21084.5 22331.6 22525.2 22547.9 22549.0 6460.9 6538.2 6540.0 6601.4 6615.7 6596.2 6660.2 6667.8 6912.5 6966.4 6928.5 6929.8 6933.4 6937.9 6939.2 6849.8 6872.1 6882.6 6901.1 6916.2 6419.1 6420.2 6445.1 6459.2 6464.9 29729.3 31001.4 31329.8 31487.4 31499.5 2215.5 2223.6 2243.0 2243.8 2247.6 20616.9 21334.9 21342.7 21349.7 21350.3 2495.9 2560.2 2610.3 2633.9 2657.8 142737.0 148872.0 155382.0 160838.0 160956.4 16134.3 16606.4 16854.9 17352.2 17732.2 26372.2 26385.9 26456.2 26554.7 26628.1 10015.6 10208.7 10261.6 10285.9 10329.9 9117.9 9135.9 9189.4 9227.9 9324.7 9565.5 9816.6 9832.2 10157.3 10194.3 2977.8 3005.5 3010.9 3037.5 3119.8 1040.7 1052.3 1069.8 1080.4 1091.5 21205.5 21261.3 21636.1 21768.7 21896.0 1106.6 1117.3 1119.3 1120.4 1124.1 11602.1 11872.9 11889.0 11928.5 12016.8 556812.0 572252.0 582208.0 599296.0 600509.0 123990.0 125717.0 128683.4 131626.0 132071.0 1084.5 1090.8 1122.4 1124.5 1130.6 18070.3 18141.8 18188.5 18384.4 18481.4 61463.0 61542.7 62610.1 62789.7 63488.4 81510.2 81742.6 81931.2 82027.6 82750.6 14985.3 15001.7 15080.3 15107.6 15107.8 22395.9 22628.8 22799.8 23189.2 23206.9 18914.0 18975.7 18981.4 19101.7 19252.7 16315.8 16703.7 16877.8 16986.8 17247.8 18829.9 19157.8 19222.2 19231.7 19607.6 14616.9 14687.4 14829.0 14844.9 14870.7 18648.2 18784.7 19043.9 19044.5 19076.7 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#3] mootools.html Cycles:180 Average:10492.86 Median:2748.80 stddev:7994.60 (290.8%) stddev-sans-first:7987.97 04:41:55 INFO - PID 20157 | Values: 1420.1 1533.0 1538.5 1542.4 1542.7 18006.5 18690.0 18717.4 18753.0 18757.5 17922.1 17941.4 18032.4 18057.3 18084.5 2725.5 2833.9 2834.8 2835.3 2836.5 2777.5 2803.0 2813.1 2814.0 2815.2 2710.2 2751.3 2837.6 2857.9 2863.0 2799.8 2848.3 2850.8 2870.6 2873.0 2650.4 2704.7 2737.8 2748.5 2749.1 16942.9 17098.8 17113.2 17123.7 17132.4 7681.7 7963.8 7980.9 7993.3 7995.1 5570.6 5682.1 5822.3 5850.5 5853.9 2057.6 2120.8 2145.9 2200.7 2224.8 31269.2 31500.1 31509.0 31552.0 31604.4 7014.1 7096.9 7262.5 7325.7 7381.7 20249.6 20335.6 20391.8 20596.7 20600.0 7578.2 7716.7 7724.7 7725.9 7727.2 6880.3 6964.0 7070.2 7243.2 7390.1 5663.7 5827.3 5846.8 5872.4 5993.7 1693.1 1708.7 1724.7 1735.1 1757.2 847.7 862.7 873.1 881.6 882.6 8757.2 9702.3 9741.8 9766.0 10018.6 781.9 784.8 786.1 798.7 799.0 5486.0 5514.9 5631.5 6082.9 6307.4 24341.9 26211.0 26295.5 26312.0 26403.3 24991.3 25406.4 25930.9 26104.3 26167.2 11393.2 11954.9 11967.0 11970.8 11980.0 9540.1 9664.3 9720.1 9777.7 9827.3 20796.3 21148.3 21152.3 21421.9 21898.7 22237.4 22272.7 22338.7 22356.4 22358.1 12117.6 12427.3 12444.8 12451.8 12486.3 15105.6 15122.9 15242.5 15280.8 15298.9 11721.2 11820.5 11947.3 12056.5 12250.8 11029.6 11279.6 11339.4 11520.9 11698.2 9943.0 10115.4 10196.1 10201.2 10206.1 7979.9 8116.5 8166.9 8194.3 8235.6 8103.7 8161.6 8188.3 8191.0 8258.1 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#4] prototype.html Cycles:180 Average:5595.22 Median:6142.40 stddev:1513.23 (24.6%) stddev-sans-first:1469.17 04:41:55 INFO - PID 20157 | Values: 541.9 578.7 580.5 585.5 592.4 6262.8 6289.3 6307.7 6315.1 6333.3 6126.3 6128.2 6136.6 6148.2 6169.3 4773.5 4975.5 4996.8 5022.1 5053.4 4857.9 4890.5 4898.5 4925.2 4936.0 4839.3 4845.5 4868.7 4872.0 4908.3 4868.6 4878.3 4907.8 4915.5 4920.9 4487.5 4507.3 4521.9 4531.5 4558.8 6078.2 6104.8 6125.4 6149.4 6214.0 5730.0 5751.5 5758.7 5785.9 5803.7 6525.5 6569.3 6580.3 6599.4 6611.3 4679.9 5020.1 5043.7 5047.4 5052.5 6701.4 6726.0 6760.8 6769.0 6936.3 5505.8 5566.2 5600.0 5601.6 5617.1 5637.6 5645.8 5648.3 5695.2 5708.3 6009.4 6017.5 6040.9 6067.9 6083.1 6590.5 6633.9 6656.9 6684.6 6688.8 1916.7 1919.6 1930.2 1940.2 1954.4 1437.0 1493.5 1502.3 1506.9 1517.8 5878.2 5961.5 5972.0 5982.3 5982.9 6461.7 6512.8 6542.4 6578.3 6584.2 5959.1 5969.5 5999.4 6003.4 6009.2 3460.7 3469.6 3478.0 3512.9 3549.3 6766.4 6855.3 6971.3 6990.4 7020.3 6779.6 6808.4 6809.5 6833.8 6876.7 6803.4 6856.2 6866.8 6959.4 6997.3 6558.4 6568.4 6609.3 6613.3 6624.9 6535.5 6536.8 6578.3 6630.2 6654.0 6904.4 6919.7 6953.4 7007.4 7009.4 5551.1 5578.2 5622.5 5627.8 5646.8 6288.1 6514.1 6522.7 6580.7 6674.1 6170.6 6336.2 6382.8 6465.3 6519.7 6291.9 6333.6 6377.6 6415.8 6416.2 6209.0 6269.0 6324.5 6416.5 6423.0 6288.5 6425.1 6451.0 6452.3 6527.8 6214.8 6249.8 6300.7 6322.8 6383.0 04:41:55 INFO - PID 20157 | 04:41:55 INFO - PID 20157 | [#5] yui.html Cycles:180 Average:28679.79 Median:18260.09 stddev:43523.08 (238.4%) stddev-sans-first:43595.34 04:41:55 INFO - PID 20157 | Values: 941.3 950.6 975.3 986.6 991.1 17243.5 17707.6 17783.0 17785.6 17824.4 16162.3 16670.1 16695.4 16724.8 16856.2 763.1 770.0 770.7 771.0 771.8 1029.9 1031.9 1035.1 1035.3 1036.6 1044.9 1090.1 1090.2 1090.8 1090.9 1074.8 1075.9 1076.2 1080.1 1081.1 766.0 789.9 791.8 792.5 793.4 17561.8 17564.0 17701.4 17746.6 17763.6 20173.1 20318.6 20434.9 20851.6 21007.5 16915.2 17007.7 17018.9 17110.8 17124.8 3519.4 3536.9 3539.1 3541.8 3546.5 162148.0 163920.0 168829.0 169008.0 169493.0 16239.3 16720.6 16763.2 16765.0 16802.0 31813.7 32631.0 32675.5 32691.3 32754.0 8852.8 8917.4 8962.9 9064.8 9084.2 4221.8 4253.9 4275.5 4281.9 4303.4 5828.9 5854.6 5899.3 5933.7 5953.6 1013.0 1013.8 1024.1 1029.0 1045.9 679.2 681.0 694.9 703.2 707.2 11587.9 11875.5 12026.0 12037.5 12452.8 726.5 728.6 732.5 737.4 742.0 5577.1 5600.8 5603.8 5638.5 5674.0 147022.3 151599.0 152227.0 152471.0 154007.0 127781.4 128496.0 128808.4 131394.0 135400.0 107430.0 108375.0 109260.0 110313.0 113639.0 12738.8 13275.5 13445.8 13583.0 13589.3 51034.7 51201.5 52737.2 53737.7 54257.0 88936.6 91494.5 95317.5 99040.5 101180.0 20060.6 20174.7 20191.9 20230.7 20447.7 20707.8 20982.5 21205.2 22053.3 22082.9 18244.5 18600.2 18894.4 19593.7 20228.6 18033.8 18160.8 18215.5 18275.6 18339.9 17920.4 18571.0 18911.1 19249.0 19478.2 13576.7 13662.4 13832.9 14054.5 14136.4 13263.3 13716.8 14552.9 14776.7 14842.6 04:41:55 INFO - PID 20157 | -------- Summary: end -------- 04:41:55 INFO - PID 20157 | 04:41:55 INFO - TEST-INFO | 20157: exit 0 04:41:55 INFO - TEST-OK | dromaeo_css | took 1107420ms 04:41:55 INFO - TEST-START | kraken 04:41:55 INFO - Initialising browser for kraken test... 04:41:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36984/getInfo.html -profile /tmp/tmpK2BHew/profile 04:41:55 INFO - TEST-INFO | started process 20527 (/builds/slave/test/build/application/firefox/firefox http://localhost:36984/getInfo.html) 04:42:00 INFO - TEST-INFO | 20527: exit 0 04:42:00 INFO - Browser initialized. 04:42:00 INFO - Running cycle 1/1 for kraken test... 04:42:00 INFO - Using env: {'DISPLAY': ':0', 04:42:00 INFO - 'HOME': '/home/cltbld', 04:42:00 INFO - 'JSGC_DISABLE_POISONING': '1', 04:42:00 INFO - 'LANG': 'en_US.UTF-8', 04:42:00 INFO - 'LANGUAGE': 'en_US:en', 04:42:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:42:00 INFO - 'LOGNAME': 'cltbld', 04:42:00 INFO - 'MAIL': '/var/mail/cltbld', 04:42:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:42:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:42:00 INFO - 'MOZ_CRASHREPORTER': '1', 04:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:42:00 INFO - 'MOZ_NO_REMOTE': '1', 04:42:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:42:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:00 INFO - 'NO_EM_RESTART': '1', 04:42:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:00 INFO - 'PWD': '/builds/slave/test', 04:42:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:42:00 INFO - 'RUST_BACKTRACE': 'full', 04:42:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:42:00 INFO - 'SHELL': '/bin/bash', 04:42:00 INFO - 'SHLVL': '1', 04:42:00 INFO - 'TERM': 'linux', 04:42:00 INFO - 'TMOUT': '86400', 04:42:00 INFO - 'USER': 'cltbld', 04:42:00 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687', 04:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:00 INFO - '_': '/tools/buildbot/bin/python'} 04:42:00 INFO - TEST-INFO | started process 20759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK2BHew/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:01 INFO - PID 20759 | 04:42:01 INFO - PID 20759 | (/builds/slave/test/build/application/firefox/firefox:20809): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:42:01 INFO - PID 20759 | 04:42:11 INFO - PID 20759 | RSS: Main: 146010112 04:42:11 INFO - PID 20759 | 04:42:14 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36984/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:15 INFO - PID 20759 | RSS: Main: 152453120 04:42:15 INFO - PID 20759 | 04:42:17 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36984/tests/kraken/driver.html?testName=audio-dft) 04:42:18 INFO - PID 20759 | RSS: Main: 151605248 04:42:18 INFO - PID 20759 | 04:42:21 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36984/tests/kraken/driver.html?testName=audio-fft) 04:42:21 INFO - PID 20759 | RSS: Main: 149245952 04:42:21 INFO - PID 20759 | 04:42:24 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36984/tests/kraken/driver.html?testName=audio-oscillator) 04:42:24 INFO - PID 20759 | RSS: Main: 149835776 04:42:24 INFO - PID 20759 | 04:42:26 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36984/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:42:27 INFO - PID 20759 | RSS: Main: 150048768 04:42:27 INFO - PID 20759 | 04:42:30 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36984/tests/kraken/driver.html?testName=imaging-darkroom) 04:42:31 INFO - PID 20759 | RSS: Main: 152252416 04:42:31 INFO - PID 20759 | 04:42:34 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36984/tests/kraken/driver.html?testName=imaging-desaturate) 04:42:35 INFO - PID 20759 | RSS: Main: 152801280 04:42:35 INFO - PID 20759 | 04:42:38 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36984/tests/kraken/driver.html?testName=json-parse-financial) 04:42:38 INFO - PID 20759 | RSS: Main: 152170496 04:42:38 INFO - PID 20759 | 04:42:40 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36984/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:42:40 INFO - PID 20759 | RSS: Main: 152260608 04:42:40 INFO - PID 20759 | 04:42:42 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:42:42 INFO - PID 20759 | RSS: Main: 152449024 04:42:42 INFO - PID 20759 | 04:42:44 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:42:45 INFO - PID 20759 | RSS: Main: 149454848 04:42:45 INFO - PID 20759 | 04:42:47 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:42:48 INFO - PID 20759 | RSS: Main: 149221376 04:42:48 INFO - PID 20759 | 04:42:51 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:42:52 INFO - PID 20759 | RSS: Main: 149884928 04:42:52 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | Cycle 1(1): loaded http://localhost:36984/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36984/tests/kraken/driver.html?testName=ai-astar) 04:42:53 INFO - PID 20759 | RSS: Main: 151298048 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | __start_tp_report 04:42:53 INFO - PID 20759 | _x_x_mozilla_page_load 04:42:53 INFO - PID 20759 | _x_x_mozilla_page_load_details 04:42:53 INFO - PID 20759 | |i|pagename|runs| 04:42:53 INFO - PID 20759 | |0;ai-astar;106;104;105;107;107;106;108;104;106;107 04:42:53 INFO - PID 20759 | |1;audio-beat-detection;115;116;118;113;114;111;113;115;140;114 04:42:53 INFO - PID 20759 | |2;audio-dft;163;162;164;163;162;163;163;163;169;168 04:42:53 INFO - PID 20759 | |3;audio-fft;73;73;73;74;73;74;74;73;73;74 04:42:53 INFO - PID 20759 | |4;audio-oscillator;78;79;78;77;77;78;78;134;78;79 04:42:53 INFO - PID 20759 | |5;imaging-gaussian-blur;114;114;111;114;115;115;118;114;114;115 04:42:53 INFO - PID 20759 | |6;imaging-darkroom;150;151;149;152;150;151;150;150;150;151 04:42:53 INFO - PID 20759 | |7;imaging-desaturate;94;93;94;94;94;94;95;94;93;95 04:42:53 INFO - PID 20759 | |8;json-parse-financial;47;47;46;45;45;48;44;44;43;47 04:42:53 INFO - PID 20759 | |9;json-stringify-tinderbox;39;40;39;40;39;39;39;39;39;40 04:42:53 INFO - PID 20759 | |10;stanford-crypto-aes;70;70;71;71;69;70;71;71;75;71 04:42:53 INFO - PID 20759 | |11;stanford-crypto-ccm;106;108;106;104;106;113;103;102;109;110 04:42:53 INFO - PID 20759 | |12;stanford-crypto-pbkdf2;154;155;154;155;159;152;151;152;154;157 04:42:53 INFO - PID 20759 | |13;stanford-crypto-sha256-iterative;66;66;60;63;66;63;61;69;60;61 04:42:53 INFO - PID 20759 | __end_tp_report 04:42:53 INFO - PID 20759 | __start_cc_report 04:42:53 INFO - PID 20759 | _x_x_mozilla_cycle_collect,694 04:42:53 INFO - PID 20759 | __end_cc_report 04:42:53 INFO - PID 20759 | __startTimestamp1506339773956__endTimestamp 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | ------- Summary: start ------- 04:42:53 INFO - PID 20759 | Number of tests: 14 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.50 stddev:1.33 (1.3%) stddev-sans-first:1.41 04:42:53 INFO - PID 20759 | Values: 106.0 104.0 105.0 107.0 107.0 106.0 108.0 104.0 106.0 107.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:115.00 stddev:8.33 (7.2%) stddev-sans-first:8.81 04:42:53 INFO - PID 20759 | Values: 115.0 116.0 118.0 113.0 114.0 111.0 113.0 115.0 140.0 114.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.00 stddev:2.45 (1.5%) stddev-sans-first:2.57 04:42:53 INFO - PID 20759 | Values: 163.0 162.0 164.0 163.0 162.0 163.0 163.0 163.0 169.0 168.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.50 stddev:0.52 (0.7%) stddev-sans-first:0.53 04:42:53 INFO - PID 20759 | Values: 73.0 73.0 73.0 74.0 73.0 74.0 74.0 73.0 73.0 74.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:78.00 stddev:17.72 (22.7%) stddev-sans-first:18.68 04:42:53 INFO - PID 20759 | Values: 78.0 79.0 78.0 77.0 77.0 78.0 78.0 134.0 78.0 79.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.50 stddev:1.71 (1.5%) stddev-sans-first:1.81 04:42:53 INFO - PID 20759 | Values: 114.0 114.0 111.0 114.0 115.0 115.0 118.0 114.0 114.0 115.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 04:42:53 INFO - PID 20759 | Values: 150.0 151.0 149.0 152.0 150.0 151.0 150.0 150.0 150.0 151.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 04:42:53 INFO - PID 20759 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 95.0 94.0 93.0 95.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#8] json-parse-financial Cycles:10 Average:45.60 Median:46.50 stddev:1.65 (3.5%) stddev-sans-first:1.67 04:42:53 INFO - PID 20759 | Values: 47.0 47.0 46.0 45.0 45.0 48.0 44.0 44.0 43.0 47.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#9] json-stringify-tinderbox Cycles:10 Average:39.30 Median:39.00 stddev:0.48 (1.2%) stddev-sans-first:0.50 04:42:53 INFO - PID 20759 | Values: 39.0 40.0 39.0 40.0 39.0 39.0 39.0 39.0 39.0 40.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#10] stanford-crypto-aes Cycles:10 Average:70.90 Median:71.00 stddev:1.60 (2.2%) stddev-sans-first:1.66 04:42:53 INFO - PID 20759 | Values: 70.0 70.0 71.0 71.0 69.0 70.0 71.0 71.0 75.0 71.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#11] stanford-crypto-ccm Cycles:10 Average:106.70 Median:107.00 stddev:3.37 (3.1%) stddev-sans-first:3.56 04:42:53 INFO - PID 20759 | Values: 106.0 108.0 106.0 104.0 106.0 113.0 103.0 102.0 109.0 110.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:154.50 stddev:2.41 (1.6%) stddev-sans-first:2.55 04:42:53 INFO - PID 20759 | Values: 154.0 155.0 154.0 155.0 159.0 152.0 151.0 152.0 154.0 157.0 04:42:53 INFO - PID 20759 | 04:42:53 INFO - PID 20759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.50 stddev:3.10 (4.8%) stddev-sans-first:3.15 04:42:53 INFO - PID 20759 | Values: 66.0 66.0 60.0 63.0 66.0 63.0 61.0 69.0 60.0 61.0 04:42:53 INFO - PID 20759 | -------- Summary: end -------- 04:42:53 INFO - PID 20759 | 04:42:54 INFO - PID 20759 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:42:54 INFO - TEST-INFO | 20759: exit 0 04:42:54 INFO - TEST-OK | kraken | took 58775ms 04:42:54 INFO - SUITE-END | took 1166s 04:42:54 INFO - Completed test suite (00:19:26) 04:42:54 INFO - javascript benchmark 04:42:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8997.563030043659, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3903.9024024399387, 3910.296146693594, 3910.569837317893, 3932.4887764590617, 3952.2688302664, 6151.046587778901, 6188.659623720693, 6263.843403914893, 6270.84322891928, 6344.39728225819, 6251.062340648905, 6293.779717709881, 6306.148669929558, 6323.74705011799, 6349.142865713139, 5596.4403559643915, 5692.5161361284345, 5699.829005129853, 5706.572007099463, 5709.05800542912, 245.72626431096774, 5636.464535869106, 5641.210230567724, 5703.03048481757, 5717.228174196493, 5258.106121959257, 5549.722513874331, 5623.690697011662, 5635.380108188096, 5688.033034384144, 5565.026120428909, 5636.15457681347, 5636.2391077204875, 5640.605157638967, 5641.238432811559, 5655.321361436654, 5664.858378540528, 5666.801661941812, 5670.943290567083, 5671.2060311556415, 6126.295476020226, 6199.690015499252, 6293.056041593752, 6312.842178945517, 6342.682865856688, 6777.830554236134, 6779.186497620268, 6801.21785994612, 6860.03959445678, 6895.379415852597, 6860.279670634612, 6879.174499060145, 6922.7923162305215, 6950.652467376611, 6977.860442791167, 6221.688915554249, 6306.1171435998995, 6392.808215753535, 6403.519736019817, 6425.903611445879, 6936.79189624312, 7025.332593403618, 7043.92956070443, 7097.89353159703, 7099.467539934422, 6725.428338591177, 6746.123004009447, 6758.357955994173, 6762.222344430355, 6765.2896445872475, 6839.0425340452375, 6841.452683785285, 6858.622775747282, 6859.759908403182, 6862.279460656659, 6766.830829229309, 6837.589744615339, 6845.726170953206, 6877.65611719412, 6877.89683154753, 6775.356341147583, 6789.422899053537, 6801.9319806801295, 6881.311868813079, 6882.655867206619, 4951.90096198074, 4986.675866068694, 5035.723035233024, 5054.620903432257, 5062.493750624982, 4566.474855391606, 4604.700694454917, 4617.699849509771, 4644.16405047088, 4659.510751371125, 6157.538184636171, 6181.1655426516845, 6257.843553911189, 6275.592086514362, 6277.027060805583, 6691.1636045494315, 6804.455643548505, 6810.523263371616, 6879.690413931385, 6886.655667216619, 6153.446189842935, 6189.288231853305, 6198.659073750897, 6204.7828326008375, 6207.286162091327, 5505.697186654692, 5533.03171944908, 5535.197396377584, 5535.25274087993, 5536.335639723178, 6930.61881596517, 6961.338672826073, 7011.1586609607175, 7023.613701246481, 7038.894416583756, 6749.392554670103, 6772.93227067723, 6826.93173068263, 6858.211305699909, 6902.206246281541, 7051.788446346618, 7052.118485189351, 7057.929420705727, 7150.821229469305, 7165.104361954755, 6632.602043877383, 6733.6296503692765, 6743.1908171019795, 6843.7604683835825, 6850.691718872762, 6887.139107611549, 6909.0, 6915.239323674492, 6924.272951340238, 6929.7228110875, 6593.538452308292, 6610.8016759497295, 6676.966615167087, 6680.665966701743, 6684.766033188815, 6503.284638689645, 6571.3100124488155, 6576.8355791103695, 6669.2997235289595, 6670.633115178712, 6468.191476065492, 6483.481321494175, 6567.9671601641685, 6572.507061970276, 6576.934230657824, 6567.343265673395, 6670.7665231716655, 6671.733130674718, 6717.429018533284, 6742.595444273359, 6418.133551970424, 6426.228852537725, 6439.549231553934, 6509.316521765147, 6538.1500404947055], "value": 6190.312744621926, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.2339511415298, 902.4855832175663, 918.5453200665671, 956.7655924298548, 957.2150836314225, 42488.57511424885, 43033.13933721324, 43066.35400468996, 43483.56516434834, 43558.56441435589, 40337.19325613486, 42916.0, 43970.78014609931, 43982.0, 44095.0, 8396.286315663176, 8531.74404767858, 8536.231739143475, 8539.74380768578, 8549.230569248766, 8355.83288334233, 8535.615897284637, 8544.359173062014, 8544.829103417958, 8547.700830470934, 8439.662413503453, 8546.0, 8546.658133674677, 8559.871601925975, 8578.0, 8462.19609137131, 8477.915220847774, 8519.53142577158, 8532.488050716976, 8532.829343413128, 8302.958485207595, 8332.916670833336, 8347.123552027011, 8347.415680902339, 8403.453775504573, 38564.228715425816, 38819.61180388188, 38843.611563884566, 38847.8057609713, 40125.197496049914, 3572.589152247499, 3592.1558433768046, 3600.279944011182, 3601.333753255656, 3608.1520842601967, 32532.349353013044, 32623.673763262537, 32762.508562371582, 32806.50790238148, 32857.50713739296, 5354.875476150013, 5455.863603409946, 5489.149181876815, 5509.256250406184, 5607.719614019283, 210872.0, 211323.0, 211650.0, 212121.0, 212780.0, 51380.0, 51774.0, 51907.74046129745, 51950.0, 52535.0, 55289.72355138198, 55320.17019744707, 55352.16971745427, 55420.445795542335, 55499.16751248734, 15176.620584485476, 15254.313555890012, 15280.083195008336, 15293.770593441108, 15411.229438528253, 12277.649458559552, 12370.381480925918, 12375.443105060294, 12383.938080309721, 12410.379481026093, 16395.836041639668, 16463.34146634145, 16574.83976121684, 16693.248803803857, 16698.6660266794, 6146.754129834846, 6210.223722034746, 6306.211723534559, 6349.206349206349, 6352.555321127569, 1082.9079528240031, 1095.6822521468844, 1097.7145942055124, 1104.5581767293147, 1105.5909313553875, 26091.08681196149, 26371.340716482242, 26414.6037809433, 26416.339591509983, 26502.0, 1179.262960649594, 1200.2678366196612, 1201.591458903977, 1219.8292239086536, 1226.9938650306692, 13744.106633068819, 13826.585202443943, 13870.930645346709, 14136.85863141376, 14152.29238538069, 320155.0, 322027.0, 323143.0, 323207.0, 323510.0, 51028.74485627622, 51409.74295128501, 56384.15423768647, 56923.43076569181, 59430.0, 1517.2413793103449, 1535.3551508366513, 1536.5928029072304, 1538.5538193923637, 1543.53693891832, 30819.537706933967, 30845.766169353872, 31052.0, 31359.216019598778, 31482.37035259328, 37118.814405928875, 41186.38220426636, 41258.174836502505, 41319.7934010328, 41368.37947430731, 257309.0, 257315.0, 257417.0, 257740.0, 257820.0, 38683.80658096691, 38692.80653596714, 38900.80549597347, 39137.80431097826, 39199.0, 46558.53441465542, 47495.76252118717, 47723.0, 48358.0, 48364.27453588128, 33648.32703345968, 34782.130446738025, 35105.0, 35426.0, 35715.82142089273, 34328.59699821089, 34335.65664343325, 34536.30927381388, 34639.4804077934, 35126.29747405089, 40037.39943900786, 40523.18953620852, 40583.1883362337, 40701.389479157246, 40745.79627101846, 30164.396712065198, 30656.540151897294, 30784.53823192699, 30794.0, 31035.224119397197, 25286.74713252844, 25519.744802551737, 26159.346016349744, 26317.73682263153, 26413.33966650849], "value": 18776.391993597623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.3471018654947, 607.4532920371665, 612.9662868542231, 621.9782307619234, 633.1642232253425, 22593.887030564845, 23258.0, 23268.883655581718, 23276.301710948657, 23283.0, 21084.472888177803, 22331.55336893265, 22525.211617593388, 22547.887260563675, 22549.0, 6460.870782584358, 6538.1827271591055, 6540.0, 6601.438877695402, 6615.669216539178, 6596.241432235282, 6660.167479065117, 6667.8333041674105, 6912.516123871331, 6966.4078553323025, 6928.515003949726, 6929.826754331131, 6933.375996160345, 6937.930620693779, 6939.20199177096, 6849.7602583909575, 6872.140982377203, 6882.621455819928, 6901.102856628606, 6916.204636466795, 6419.069234960951, 6420.229572451336, 6445.097686323918, 6459.224893012869, 6464.93535064648, 29729.25676858074, 31001.37997240065, 31329.8433507831, 31487.37025259505, 31499.527507087412, 2215.501512159767, 2223.644216925284, 2243.0, 2243.7868402501736, 2247.5841969235744, 20616.896915515477, 21334.893325533427, 21342.679859802116, 21349.679754803692, 21350.252741153985, 2495.862727549984, 2560.244727805302, 2610.2821724026044, 2633.9473210535875, 2657.7608015278524, 142737.0, 148872.0, 155382.0, 160838.0, 160956.3904360953, 16134.273957671932, 16606.418775342805, 16854.915725421295, 17352.219150138273, 17732.20205090774, 26372.208833735018, 26385.868070659522, 26456.206313810617, 26554.734452655226, 26628.06801761929, 10015.649452269136, 10208.693739187836, 10261.640842570474, 10285.9485702571, 10329.9483502582, 9117.863232051524, 9135.908640913638, 9189.356745027784, 9227.938787039577, 9324.720258392259, 9565.473898935486, 9816.558254878548, 9832.164266037467, 10157.339772914886, 10194.337368071052, 2977.7766667500023, 3005.5040918248683, 3010.9397812043635, 3037.544368344722, 3119.844007799601, 1040.7450174707244, 1052.347544522401, 1069.7593041565742, 1080.4327727942737, 1091.476091476096, 21205.469863253544, 21261.255856044965, 21636.134554617638, 21768.673469897964, 21896.0, 1106.6403418888733, 1117.279354816139, 1119.261287550213, 1120.38938778366, 1124.1007194244735, 11602.129840262014, 11872.940635296769, 11889.0, 11928.46321915516, 12016.759664806656, 556812.0, 572252.0, 582208.0, 599296.0, 600509.0, 123990.0, 125717.0, 128683.35658321835, 131626.0, 132071.0, 1084.4631907206142, 1090.820014697566, 1122.4443900269255, 1124.4546394998583, 1130.6494986554194, 18070.277188912823, 18141.81858181454, 18188.454346369632, 18384.44846654549, 18481.445556633324, 61463.0, 61542.69228653828, 62610.06084908821, 62789.68605156945, 63488.36511634824, 81510.18489815026, 81742.59128704318, 81931.18068819235, 82027.58986205031, 82750.58624706838, 14985.325660345092, 15001.6999660004, 15080.321385537896, 15107.622309442351, 15107.773383399039, 22395.888020560444, 22628.773712262668, 22799.772002279766, 23189.188378406674, 23206.883965580066, 18914.0, 18975.715364269272, 18981.43055708331, 19101.713474298176, 19252.711209332152, 16315.755263670817, 16703.749443758596, 16877.831221687626, 16986.830131698523, 17247.827521725125, 18829.90585047066, 19157.80842191616, 19222.231110755412, 19231.7499362541, 19607.60784784325, 14616.853831461975, 14687.41250349974, 14829.0, 14844.851551484348, 14870.702585948437, 18648.160832762285, 18784.7182292263, 19043.90478047656, 19044.523886902938, 19076.713849292548], "value": 12631.648333626641, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.0911416693318, 1532.954011379659, 1538.4769178479314, 1542.4138827245645, 1542.6529030968034, 18006.549836254082, 18690.0, 18717.438476845717, 18753.0, 18757.531061723465, 17922.103894805274, 17941.372051978182, 18032.368867089655, 18057.27770889163, 18084.457466276, 2725.509408306504, 2833.9291517712018, 2834.8440835754027, 2835.2770043638843, 2836.4610723962383, 2777.527820270559, 2803.032953631004, 2813.127930341601, 2813.957790633142, 2815.155453363993, 2710.214037929008, 2751.284665986848, 2837.574363845419, 2857.9142625721156, 2862.9570556441568, 2799.7760179185716, 2848.316404063031, 2850.757685596727, 2870.583765354033, 2872.971270287291, 2650.3771613670774, 2704.6619172603423, 2737.8220415673118, 2748.532749432601, 2749.0653177919603, 16942.915285423616, 17098.829011709848, 17113.22990465432, 17123.74314385285, 17132.40036598713, 7681.692732290701, 7963.840723185562, 7980.920190798076, 7993.280604745601, 7995.120536740954, 5570.601487367062, 5682.090865461548, 5822.301323841124, 5850.531957443394, 5853.912191317134, 2057.5884823035303, 2120.830333573326, 2145.8605190662556, 2200.713907192071, 2224.822014238857, 31269.218269543217, 31500.054998350086, 31508.99999999977, 31552.0, 31604.36791264185, 7014.123234595676, 7096.92903070973, 7262.527935684164, 7325.706971721178, 7381.741639042715, 20249.595008099757, 20335.593288134154, 20391.79608203899, 20596.691049634268, 20600.0, 7578.204288549733, 7716.691332346643, 7724.6523906424345, 7725.922740772633, 7727.1886451922865, 6880.277570855088, 6963.9651801740665, 7070.197099739534, 7243.167035790952, 7390.076240469942, 5663.688497132614, 5827.271591051119, 5846.766129354864, 5872.354041055481, 5993.670348130808, 1693.119577819527, 1708.6838934797102, 1724.6895558799536, 1735.1064201936013, 1757.1741281597629, 847.7456762971084, 862.6764963138822, 873.1137895036543, 881.6341218394423, 882.6116508736136, 8757.211850933447, 9702.320837541305, 9741.756456088655, 9765.951170244103, 10018.64934727281, 781.9100803407681, 784.8077221080757, 786.080286065305, 798.6685525506966, 799.0371602219312, 5486.039943009957, 5514.91727624086, 5631.493165615114, 6082.878342433127, 6307.432331090224, 24341.878290608787, 26211.0, 26295.474090518084, 26312.0, 26403.339916502242, 24991.25865393284, 25406.364840879127, 25930.870345648527, 26104.34739131537, 26167.214983550522, 11393.202475826778, 11954.940225298991, 11967.042636589053, 11970.82043769344, 11980.0, 9540.093691099335, 9664.323497355399, 9720.125188733047, 9777.706668799663, 9827.31208815376, 20796.27213047536, 21148.259810906544, 21152.259670911444, 21421.892890535448, 21898.67151992753, 22237.444063898532, 22272.665910011037, 22338.664920025887, 22356.441088972257, 22358.105675772786, 12117.636470905887, 12427.254364738144, 12444.81332779991, 12451.813222801484, 12486.250824950532, 15105.622359441102, 15122.924385378443, 15242.466513671969, 15280.847191528386, 15298.923505382401, 11721.238119522204, 11820.468078936297, 11947.283163010246, 12056.51773929033, 12250.816237756262, 11029.61396351156, 11279.605213817476, 11339.376334301693, 11520.884791152317, 11698.181127321006, 9942.950285248528, 10115.39307641544, 10196.082352588302, 10201.234907381828, 10206.132478739093, 7979.880301795361, 8116.472429292077, 8166.918330816616, 8194.262516373556, 8235.588220588827, 8103.675852966051, 8161.632726527439, 8188.2630563249595, 8190.959045204736, 8258.132896045829], "value": 7241.525793179416, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.9403865574786, 578.69907648023, 580.5152697497589, 585.4818485640209, 592.3898384663797, 6262.812115636527, 6289.276733175686, 6307.747690092397, 6315.084312774645, 6333.303336632966, 6126.295476020259, 6128.172696685946, 6136.631802091878, 6148.169997050397, 6169.334279744467, 4773.474917759044, 4975.477574854643, 4996.750162491879, 5022.07091688038, 5053.418856831457, 4857.902841943159, 4890.535399137077, 4898.51014898509, 4925.211966085427, 4936.0, 4839.298301746245, 4845.466998630148, 4868.732219727914, 4871.975640121795, 4908.263760435928, 4868.63485238605, 4878.268169824874, 4907.7546122694075, 4915.459299477036, 4920.876978075541, 4487.461504619434, 4507.323901414814, 4521.932171017437, 4531.462381066581, 4558.772061396917, 6078.209832721718, 6104.786332478387, 6125.418085281891, 6149.354317796657, 6214.0, 5730.0, 5751.453611906904, 5758.654480731169, 5785.855353616151, 5803.7098145093, 6525.543211975164, 6569.277379488252, 6580.309067547886, 6599.436304395621, 6611.338866113399, 4679.859604211879, 5020.121478741242, 5043.67216130954, 5047.425812835444, 5052.494750524918, 6701.352965578569, 6725.966370168167, 6760.8309792255095, 6769.0, 6936.341047600419, 5505.779768809202, 5566.192902029264, 5599.972000139973, 5601.6358936668985, 5617.096118197814, 5637.56242158247, 5645.774169033192, 5648.265725455664, 5695.231143795618, 5708.31500219968, 6009.369016253318, 6017.548683848641, 6040.939590604125, 6067.908981365283, 6083.11794789753, 6590.505712071614, 6633.86732265352, 6656.933430665728, 6684.63234522106, 6688.799336019927, 1916.6550020996076, 1919.5680971781237, 1930.1700268884233, 1940.223910435837, 1954.355062829263, 1436.992815035918, 1493.5071426429251, 1502.3239542206052, 1506.879449644026, 1517.840626734199, 5878.177055212273, 5961.463468287875, 5971.970140149271, 5982.341942386334, 5982.880342393128, 6461.741530338733, 6512.772052978123, 6542.443892269211, 6578.342165783383, 6584.176977877765, 5959.0763431668165, 5969.522438204933, 5999.370066142992, 6003.369646187175, 6009.1587177795145, 3460.740444466675, 3469.61834198238, 3477.9913824990467, 3512.8946131616094, 3549.343371476285, 6766.424853887376, 6855.298139004981, 6971.302869713089, 6990.370866622028, 7020.262872398427, 6779.610194902549, 6808.446832437836, 6809.489288303397, 6833.829154271084, 6876.69054892531, 6803.353681400259, 6856.24581296047, 6866.759663411856, 6959.4432445404245, 6997.335253150943, 6558.442532384801, 6568.441682456853, 6609.3060228675295, 6613.27254002069, 6624.867502650017, 6535.509836762357, 6536.8365790855605, 6578.309277525982, 6630.248446894678, 6653.9667301663185, 6904.378605925491, 6919.688614012281, 6953.4089602384365, 7007.439404847702, 7009.369156775914, 5551.139573366135, 5578.246936663661, 5622.493975542221, 5627.831165065055, 5646.830595082154, 6288.057785392943, 6514.120593719977, 6522.706478208397, 6580.696756135506, 6674.098996635586, 6170.629762214281, 6336.17629708135, 6382.776602818879, 6465.321141280286, 6519.7392104315295, 6291.905621415774, 6333.58831675958, 6377.553571249968, 6415.807525774234, 6416.197975253093, 6208.9689551551955, 6269.0, 6324.525660575568, 6416.454601358937, 6423.0, 6288.528360373082, 6425.10048593188, 6451.0, 6452.290248072803, 6527.771527996498, 6214.844628884314, 6249.812505624838, 6300.71646775887, 6322.830276398881, 6383.0], "value": 5177.575236671152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [941.3410612571201, 950.576993238009, 975.3172779054659, 986.6497393425334, 991.0782971836192, 17243.48269551914, 17707.64584708305, 17783.0, 17785.644287114264, 17824.376146834846, 16162.2726977286, 16670.083145427026, 16695.415660451887, 16724.83275167248, 16856.157192140407, 763.0766772205624, 769.9653515591784, 770.6955752477768, 771.0208035794537, 771.7839005078587, 1029.8867124616286, 1031.881333646629, 1035.1098055672117, 1035.29082578434, 1036.5905467340397, 1044.8693913260843, 1090.1224514266012, 1090.2095980414176, 1090.7872964771873, 1090.8963648453423, 1074.7742973975435, 1075.9408232547169, 1076.1874784537702, 1080.114306268852, 1081.145894743152, 765.9693612255559, 789.9328557072656, 791.7505985614513, 792.5324058805274, 793.3573805217734, 17561.824381756276, 17564.0, 17701.38045168413, 17746.645067098714, 17763.555911102198, 20173.092210850547, 20318.593628127503, 20434.897825510925, 20851.58296834055, 21007.47481312964, 16915.238814253265, 17007.744883826752, 17018.914905425518, 17110.828891711048, 17124.828751712448, 3519.419295816187, 3536.9469457958153, 3539.0975301298263, 3541.7520773545602, 3546.5212196353677, 162148.0, 163920.0, 168829.0, 169008.0, 169493.0, 16239.269232884548, 16720.58198545022, 16763.1618419081, 16765.0, 16802.0, 31813.68186318107, 32631.0, 32675.509867352008, 32691.346173076407, 32754.0, 8852.778680532909, 8917.375783695079, 8962.910370896208, 9064.773380665536, 9084.18242358191, 4221.810018549173, 4253.893652658709, 4275.48694156697, 4281.892952676208, 4303.4190384298345, 5828.941710582924, 5854.590178687451, 5899.262592175978, 5933.6736479493175, 5953.642781433128, 1012.9645462408928, 1013.8479228115841, 1024.0680980307884, 1029.0481304793036, 1045.9110975567162, 679.2188982669969, 680.9897851532231, 694.9409300209438, 703.1562125449481, 707.1549498349526, 11587.88412115868, 11875.524979000744, 12026.0, 12037.518499260106, 12452.750944981231, 726.4950859152943, 728.617475825196, 732.4506620034974, 737.4337306174542, 742.0, 5577.0797818359515, 5600.775968961278, 5603.80386686464, 5638.548916086703, 5673.971630141823, 147022.26488867486, 151599.0, 152227.0, 152471.0, 154007.0, 127781.3610931958, 128496.0, 128808.3559582196, 131394.0, 135400.0, 107430.0, 108375.0, 109260.0, 110313.0, 113639.0, 12738.808917866054, 13275.535356262484, 13445.798313025116, 13583.0, 13589.320533973065, 51034.74482627563, 51201.487985119675, 52737.20894186514, 53737.731311343196, 54257.0, 88936.555317223, 91494.54252728693, 95317.52341238249, 99040.50479747556, 101180.0, 20060.59878802445, 20174.69737953961, 20191.899040504704, 20230.696539551624, 20447.693284600446, 20707.79292207059, 20982.47543811417, 21205.15179392807, 22053.33839984803, 22082.88958555197, 18244.543886402946, 18600.162992665093, 18894.433167005012, 19593.70609440831, 20228.59542809165, 18033.819661803213, 18160.818391815912, 18215.544611384823, 18275.634487310446, 18339.908300458414, 17920.372786952394, 18571.0, 18911.054447277307, 19249.037548122258, 19478.220871164995, 13576.660583485493, 13662.385192666155, 13832.930835345758, 14054.508092217133, 14136.363863626368, 13263.270520121487, 13716.794248086087, 14552.85447145515, 14776.704465910836, 14842.554723358317], "value": 9637.872061171583, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1383.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 104.0, 105.0, 107.0, 107.0, 106.0, 108.0, 104.0, 106.0, 107.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 118.0, 113.0, 114.0, 111.0, 113.0, 115.0, 140.0, 114.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 163.0, 162.0, 163.0, 163.0, 163.0, 169.0, 168.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 74.0, 73.0, 74.0, 74.0, 73.0, 73.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 77.0, 77.0, 78.0, 78.0, 134.0, 78.0, 79.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 111.0, 114.0, 115.0, 115.0, 118.0, 114.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 149.0, 152.0, 150.0, 151.0, 150.0, 150.0, 150.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 93.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 46.0, 45.0, 45.0, 48.0, 44.0, 44.0, 43.0, 47.0], "value": 45.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 40.0, 39.0, 40.0, 39.0, 39.0, 39.0, 39.0, 39.0, 40.0], "value": 39.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 71.0, 69.0, 70.0, 71.0, 71.0, 75.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 108.0, 106.0, 104.0, 106.0, 113.0, 103.0, 102.0, 109.0, 110.0], "value": 106.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 154.0, 155.0, 159.0, 152.0, 151.0, 152.0, 154.0, 157.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 60.0, 63.0, 66.0, 63.0, 61.0, 69.0, 60.0, 61.0], "value": 63.5, "unit": "score"}]}]} 04:42:54 INFO - Return code: 0 04:42:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:42:54 INFO - # TBPL SUCCESS # 04:42:54 INFO - Running post-action listener: _package_coverage_data 04:42:54 INFO - Running post-action listener: _resource_record_post_action 04:42:54 INFO - [mozharness: 2017-09-25 11:42:54.772343Z] Finished run-tests step (success) 04:42:54 INFO - Running post-run listener: _resource_record_post_run 04:42:54 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328413184; Read time: 12; Write time: 220224 04:42:54 INFO - TinderboxPrint: CPU usage
13.1% 04:42:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:42:54 INFO - TinderboxPrint: I/O write bytes / time
328,413,184 / 220,224 04:42:54 INFO - TinderboxPrint: CPU idle
8,205.4 (86.9%) 04:42:54 INFO - TinderboxPrint: CPU user
1,187.2 (12.6%) 04:42:54 INFO - TinderboxPrint: Swap in / out
0 / 0 04:42:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13398016; Read time: 0; Write time: 1168 04:42:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:42:54 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 315015168; Read time: 0; Write time: 219056 04:42:55 INFO - Running post-run listener: _upload_blobber_files 04:42:55 INFO - Blob upload gear active. 04:42:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:42:55 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 04:42:55 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'] 04:42:55 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 04:42:55 INFO - (blobuploader) - INFO - Open directory for files ... 04:42:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:42:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:43:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:02 INFO - (blobuploader) - INFO - Done attempting. 04:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:43:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:03 INFO - (blobuploader) - INFO - Done attempting. 04:43:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:43:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:43:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:04 INFO - (blobuploader) - INFO - Done attempting. 04:43:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:43:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:05 INFO - (blobuploader) - INFO - Done attempting. 04:43:05 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:05 INFO - Return code: 0 04:43:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/443535d5d1e5496c952913c0cbeee22a1a6316d91d37b3b7058fe6528b07f1be84a0ca4aa3e1322d52e3cc99a51948b3cce93fe6db31d6333739bdf4fece31a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754ccc90c1c4eb8db12de36948c008ff53a9e00fcdafdb3706afa8a11c51d05c07451ea8df31b737c6b085faacbccf594d90c953161e6a0bcd7a60e3fc26209", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6399c9536c1365b5769ba2eb1bc7a0af2d955783213539c6805e926e281e73aec1eaa5bd1550d250ffd7bfdaaf5937f02975b5391b35dc4a28aee6ec675803ef", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21109ce8ac9f66ec7d271258c19b763962719fc7800c23b6a4b0652019632f41fd4ac640f4bd9dbed7b4ed9b9d61e9606c71a9a0621211f8071f94ec2eb4334d"} 04:43:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:05 INFO - Contents: 04:43:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/443535d5d1e5496c952913c0cbeee22a1a6316d91d37b3b7058fe6528b07f1be84a0ca4aa3e1322d52e3cc99a51948b3cce93fe6db31d6333739bdf4fece31a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754ccc90c1c4eb8db12de36948c008ff53a9e00fcdafdb3706afa8a11c51d05c07451ea8df31b737c6b085faacbccf594d90c953161e6a0bcd7a60e3fc26209", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6399c9536c1365b5769ba2eb1bc7a0af2d955783213539c6805e926e281e73aec1eaa5bd1550d250ffd7bfdaaf5937f02975b5391b35dc4a28aee6ec675803ef", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21109ce8ac9f66ec7d271258c19b763962719fc7800c23b6a4b0652019632f41fd4ac640f4bd9dbed7b4ed9b9d61e9606c71a9a0621211f8071f94ec2eb4334d"} 04:43:05 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:05 INFO - Copying logs to upload dir... 04:43:05 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.194310 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-25 04:43:05.577440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:05.582530) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 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/443535d5d1e5496c952913c0cbeee22a1a6316d91d37b3b7058fe6528b07f1be84a0ca4aa3e1322d52e3cc99a51948b3cce93fe6db31d6333739bdf4fece31a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754ccc90c1c4eb8db12de36948c008ff53a9e00fcdafdb3706afa8a11c51d05c07451ea8df31b737c6b085faacbccf594d90c953161e6a0bcd7a60e3fc26209", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6399c9536c1365b5769ba2eb1bc7a0af2d955783213539c6805e926e281e73aec1eaa5bd1550d250ffd7bfdaaf5937f02975b5391b35dc4a28aee6ec675803ef", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21109ce8ac9f66ec7d271258c19b763962719fc7800c23b6a4b0652019632f41fd4ac640f4bd9dbed7b4ed9b9d61e9606c71a9a0621211f8071f94ec2eb4334d"} build_url:https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011410 build_url: 'https://queue.taskcluster.net/v1/task/EQ7NM8uLRAmZjEZGwDse2A/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/443535d5d1e5496c952913c0cbeee22a1a6316d91d37b3b7058fe6528b07f1be84a0ca4aa3e1322d52e3cc99a51948b3cce93fe6db31d6333739bdf4fece31a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754ccc90c1c4eb8db12de36948c008ff53a9e00fcdafdb3706afa8a11c51d05c07451ea8df31b737c6b085faacbccf594d90c953161e6a0bcd7a60e3fc26209", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6399c9536c1365b5769ba2eb1bc7a0af2d955783213539c6805e926e281e73aec1eaa5bd1550d250ffd7bfdaaf5937f02975b5391b35dc4a28aee6ec675803ef", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21109ce8ac9f66ec7d271258c19b763962719fc7800c23b6a4b0652019632f41fd4ac640f4bd9dbed7b4ed9b9d61e9606c71a9a0621211f8071f94ec2eb4334d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:05.614417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:05.614758) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506337921.238661-1911879687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004531 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:05.697974) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:43:05.698288) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:43:05.698603) ========= ========= Total master_lag: 0.20 =========