builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1506336898.85 results: success (0) revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:58.845461) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:58.845893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:58.846169) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:58.890052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:58.890320) ========= 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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 03:54:58-- 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% 105M=0s 2017-09-25 03:54:59 (105 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172356 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:59.251921) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:59.252213) ========= 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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018672 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:59.317506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:54:59.323838) ========= 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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 03:54:59,375 truncating revision to first 12 chars 2017-09-25 03:54:59,375 Setting DEBUG logging. 2017-09-25 03:54:59,375 attempt 1/10 2017-09-25 03:54:59,375 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 03:54:59,724 unpacking tar archive at: mozilla-central-5f3f19824efa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.701493 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 03:55:00.048675) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 03:55:00.048978) ========= script_repo_revision: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 03:55:00.049355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 03:55:00.053632) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 03:55:00.072022) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-25 03:55:00.072349) ========= /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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:55:00 INFO - MultiFileLogger online at 20170925 03:55:00 in /builds/slave/test 03:55:00 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 03:55:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:55:00 INFO - {'append_to_log': False, 03:55:00 INFO - 'base_work_dir': '/builds/slave/test', 03:55:00 INFO - 'blob_upload_branch': 'Firefox', 03:55:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:55:00 INFO - 'branch': 'Firefox', 03:55:00 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:00 INFO - 'code_coverage': False, 03:55:00 INFO - 'config_files': ('talos/linux_config.py',), 03:55:00 INFO - 'default_actions': ('clobber', 03:55:00 INFO - 'read-buildbot-config', 03:55:00 INFO - 'download-and-extract', 03:55:00 INFO - 'populate-webroot', 03:55:00 INFO - 'create-virtualenv', 03:55:00 INFO - 'install', 03:55:00 INFO - 'setup-mitmproxy', 03:55:00 INFO - 'run-tests'), 03:55:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:00 INFO - 'disable_ccov_upload': False, 03:55:00 INFO - 'disable_stylo': False, 03:55:00 INFO - 'download_minidump_stackwalk': True, 03:55:00 INFO - 'download_symbols': 'ondemand', 03:55:00 INFO - 'enable_stylo': False, 03:55:00 INFO - 'enable_webrender': False, 03:55:00 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:55:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:00 INFO - 'gecko_profile': False, 03:55:00 INFO - 'gecko_profile_interval': 0, 03:55:00 INFO - 'installer_path': 'installer.exe', 03:55:00 INFO - 'log_level': 'info', 03:55:00 INFO - 'log_name': 'talos', 03:55:00 INFO - 'log_to_console': True, 03:55:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:55:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:55:00 INFO - 'opt_config_files': (), 03:55:00 INFO - 'pip_index': False, 03:55:00 INFO - 'suite': 'dromaeojs-e10s', 03:55:00 INFO - 'system_bits': '32', 03:55:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:00 INFO - 'title': 'talos-linux64-ix-039', 03:55:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:00 INFO - 'use_talos_json': True, 03:55:00 INFO - 'verify': 'False', 03:55:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:55:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:00 INFO - 'work_dir': 'build'} 03:55:00 INFO - [mozharness: 2017-09-25 10:55:00.257511Z] Running clobber step. 03:55:00 INFO - Running pre-action listener: _resource_record_pre_action 03:55:00 INFO - Running main action method: clobber 03:55:00 INFO - rmtree: /builds/slave/test/build 03:55:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:55:01 INFO - Running post-action listener: _resource_record_post_action 03:55:01 INFO - [mozharness: 2017-09-25 10:55:01.264891Z] Finished clobber step (success) 03:55:01 INFO - [mozharness: 2017-09-25 10:55:01.264987Z] Running read-buildbot-config step. 03:55:01 INFO - Running pre-action listener: _resource_record_pre_action 03:55:01 INFO - Running main action method: read_buildbot_config 03:55:01 INFO - Using buildbot properties: 03:55:01 INFO - { 03:55:01 INFO - "project": "", 03:55:01 INFO - "product": "firefox", 03:55:01 INFO - "who": "archaeopteryx@coole-files.de", 03:55:01 INFO - "installer_path": "public/build/target.tar.bz2", 03:55:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:55:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:55:01 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:55:01 INFO - "stage_platform": "linux64-qr", 03:55:01 INFO - "basedir": "/builds/slave/test", 03:55:01 INFO - "buildnumber": 95, 03:55:01 INFO - "platform": "ubuntu64_hw_qr", 03:55:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:55:01 INFO - "slavebuilddir": "test", 03:55:01 INFO - "taskId": "W7-OPXhRTdyISOjU8AQT7A", 03:55:01 INFO - "branch": "mozilla-central", 03:55:01 INFO - "script_repo_revision": "production", 03:55:01 INFO - "revision": "5f3f19824efa14cc6db546baf59c54a0fc15ddc9", 03:55:01 INFO - "slavename": "talos-linux64-ix-039", 03:55:01 INFO - "repo_path": "mozilla-central" 03:55:01 INFO - } 03:55:01 INFO - Finding installer, test and symbols from parent task. 03:55:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W7-OPXhRTdyISOjU8AQT7A'}, attempt #1 03:55:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W7-OPXhRTdyISOjU8AQT7A'}, attempt #1 03:55:02 INFO - Task dependencies: SaUzvixmSkCRJGqkRd3BZQ 03:55:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ'}, attempt #1 03:55:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json 03:55:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip 03:55:02 INFO - Running post-action listener: _resource_record_post_action 03:55:02 INFO - [mozharness: 2017-09-25 10:55:02.578981Z] Finished read-buildbot-config step (success) 03:55:02 INFO - [mozharness: 2017-09-25 10:55:02.579198Z] Running download-and-extract step. 03:55:02 INFO - Running pre-action listener: _resource_record_pre_action 03:55:02 INFO - Running main action method: download_and_extract 03:55:02 INFO - mkdir: /builds/slave/test/build/tests 03:55:02 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')]} 03:55:02 INFO - trying https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json 03:55:02 INFO - Downloading https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:55:03 INFO - Downloaded 989 bytes. 03:55:03 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:55:03 INFO - Using the following test package requirements: 03:55:03 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:55:03 INFO - u'common': [u'target.common.tests.zip'], 03:55:03 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:55:03 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:55:03 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:55:03 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:55:03 INFO - u'mozbase': [u'target.common.tests.zip'], 03:55:03 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:55:03 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:55:03 INFO - u'web-platform': [u'target.common.tests.zip', 03:55:03 INFO - u'target.web-platform.tests.tar.gz'], 03:55:03 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:55:03 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:55:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip 03:55:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:55:03 INFO - Fetch https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.common.tests.zip into memory 03:55:04 INFO - Content-Length response header: 54943940 03:55:04 INFO - Bytes received: 54943940 03:55:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip 03:55:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:55:15 INFO - Fetch https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.talos.tests.zip into memory 03:55:15 INFO - Content-Length response header: 14513823 03:55:15 INFO - Bytes received: 14513823 03:55:16 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')]} 03:55:16 INFO - trying https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:16 INFO - Downloading https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:55:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:55:18 INFO - Downloaded 61631429 bytes. 03:55:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:18 INFO - mkdir: /builds/slave/test/properties 03:55:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:55:18 INFO - Writing to file /builds/slave/test/properties/build_url 03:55:18 INFO - Contents: 03:55:18 INFO - build_url:https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 03:55:18 INFO - Running post-action listener: _resource_record_post_action 03:55:18 INFO - Running post-action listener: find_tests_for_verification 03:55:18 INFO - Running post-action listener: set_extra_try_arguments 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.099635Z] Finished download-and-extract step (success) 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.099828Z] Running populate-webroot step. 03:55:18 INFO - Running pre-action listener: _resource_record_pre_action 03:55:18 INFO - Running main action method: populate_webroot 03:55:18 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:55:18 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tresize', 03:55:18 INFO - 'tcanvasmark']}, 03:55:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:55:18 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['dromaeo_css', 03:55:18 INFO - 'kraken']}, 03:55:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:18 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['damp', 'tps']}, 03:55:18 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['damp', 'tps']}, 03:55:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:55:18 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['dromaeo_dom']}, 03:55:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:55:18 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['basic_compositor_video', 03:55:18 INFO - 'glvideo']}, 03:55:18 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:55:18 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['ts_paint_webext', 03:55:18 INFO - 'tp5o_webext']}, 03:55:18 INFO - 'other-e10s': {'tests': ['a11yr', 03:55:18 INFO - 'ts_paint', 03:55:18 INFO - 'tpaint', 03:55:18 INFO - 'sessionrestore', 03:55:18 INFO - 'sessionrestore_many_windows', 03:55:18 INFO - 'sessionrestore_no_auto_restore', 03:55:18 INFO - 'tabpaint']}, 03:55:18 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['a11yr', 03:55:18 INFO - 'ts_paint', 03:55:18 INFO - 'tpaint', 03:55:18 INFO - 'sessionrestore', 03:55:18 INFO - 'sessionrestore_many_windows', 03:55:18 INFO - 'sessionrestore_no_auto_restore', 03:55:18 INFO - 'tabpaint']}, 03:55:18 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:55:18 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:55:18 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['perf_reftest_singletons']}, 03:55:18 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['perf_reftest']}, 03:55:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:55:18 INFO - 'tsvgr_opacity', 03:55:18 INFO - 'tart', 03:55:18 INFO - 'tscrollx', 03:55:18 INFO - 'tsvg_static']}, 03:55:18 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tsvgx', 03:55:18 INFO - 'tsvgr_opacity', 03:55:18 INFO - 'tart', 03:55:18 INFO - 'tscrollx', 03:55:18 INFO - 'tsvg_static']}, 03:55:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:55:18 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo'], 03:55:18 INFO - 'tests': ['tp5o']}, 03:55:18 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--disable-stylo', 03:55:18 INFO - '--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:55:18 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:55:18 INFO - 'talos_options': ['--stylo-threads=1', 03:55:18 INFO - '--mitmproxy', 03:55:18 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:55:18 INFO - '--firstNonBlankPaint'], 03:55:18 INFO - 'tests': ['tp6_google', 03:55:18 INFO - 'tp6_youtube', 03:55:18 INFO - 'tp6_amazon', 03:55:18 INFO - 'tp6_facebook']}, 03:55:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--xperf_path', 03:55:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:18 INFO - 'tests': ['tp5n']}, 03:55:18 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:55:18 INFO - 'talos_options': ['--disable-stylo', 03:55:18 INFO - '--xperf_path', 03:55:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:18 INFO - 'tests': ['tp5n']}}} 03:55:18 INFO - Running post-action listener: _resource_record_post_action 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.119142Z] Finished populate-webroot step (success) 03:55:18 INFO - [mozharness: 2017-09-25 10:55:18.119231Z] Running create-virtualenv step. 03:55:18 INFO - Running pre-action listener: _resource_record_pre_action 03:55:18 INFO - Running main action method: create_virtualenv 03:55:18 INFO - Creating virtualenv /builds/slave/test/build/venv 03:55:18 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:55:18 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:55:18 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:55:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:55:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:55:18 INFO - Installing distribute.............................................................................................................................................................................................done. 03:55:18 INFO - Installing pip...............done. 03:55:19 INFO - Return code: 0 03:55:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:55:19 INFO - Reading from file tmpfile_stdout 03:55:19 INFO - Output received: 03:55:19 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:55:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:55:19 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')]} 03:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:19 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')]} 03:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:19 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:19 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 03:55:19 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 03:55:19 INFO - Using env: {'DISPLAY': ':0', 03:55:19 INFO - 'HOME': '/home/cltbld', 03:55:19 INFO - 'LANG': 'en_US.UTF-8', 03:55:19 INFO - 'LANGUAGE': 'en_US:en', 03:55:19 INFO - 'LOGNAME': 'cltbld', 03:55:19 INFO - 'MAIL': '/var/mail/cltbld', 03:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:19 INFO - 'MOZ_NO_REMOTE': '1', 03:55:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:19 INFO - 'NO_EM_RESTART': '1', 03:55:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:19 INFO - 'PWD': '/builds/slave/test', 03:55:19 INFO - 'SHELL': '/bin/bash', 03:55:19 INFO - 'SHLVL': '1', 03:55:19 INFO - 'TERM': 'linux', 03:55:19 INFO - 'TMOUT': '86400', 03:55:19 INFO - 'USER': 'cltbld', 03:55:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:19 INFO - '_': '/tools/buildbot/bin/python'} 03:55:20 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:55:20 INFO - Downloading/unpacking pip>=1.5 03:55:20 INFO - Running setup.py egg_info for package pip 03:55:20 INFO - warning: no files found matching 'pip/cacert.pem' 03:55:20 INFO - warning: no files found matching '*.html' under directory 'docs' 03:55:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:55:20 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:55:20 INFO - Installing collected packages: pip 03:55:20 INFO - Found existing installation: pip 1.1 03:55:20 INFO - Uninstalling pip: 03:55:20 INFO - Successfully uninstalled pip 03:55:20 INFO - Running setup.py install for pip 03:55:20 INFO - warning: no files found matching 'pip/cacert.pem' 03:55:20 INFO - warning: no files found matching '*.html' under directory 'docs' 03:55:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:55:20 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:55:20 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:55:20 INFO - Successfully installed pip 03:55:20 INFO - Cleaning up... 03:55:20 INFO - Return code: 0 03:55:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:55:20 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')]} 03:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:20 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')]} 03:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:20 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:20 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 03:55:20 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 03:55:20 INFO - Using env: {'DISPLAY': ':0', 03:55:20 INFO - 'HOME': '/home/cltbld', 03:55:20 INFO - 'LANG': 'en_US.UTF-8', 03:55:20 INFO - 'LANGUAGE': 'en_US:en', 03:55:20 INFO - 'LOGNAME': 'cltbld', 03:55:20 INFO - 'MAIL': '/var/mail/cltbld', 03:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:20 INFO - 'MOZ_NO_REMOTE': '1', 03:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:20 INFO - 'NO_EM_RESTART': '1', 03:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:20 INFO - 'PWD': '/builds/slave/test', 03:55:20 INFO - 'SHELL': '/bin/bash', 03:55:20 INFO - 'SHLVL': '1', 03:55:20 INFO - 'TERM': 'linux', 03:55:20 INFO - 'TMOUT': '86400', 03:55:20 INFO - 'USER': 'cltbld', 03:55:20 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:20 INFO - '_': '/tools/buildbot/bin/python'} 03:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:20 INFO - Downloading/unpacking psutil>=3.1.1 03:55:20 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 03:55:20 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 03:55:20 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 03:55:20 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 03:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:55:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:21 INFO - Installing collected packages: psutil 03:55:21 INFO - Running setup.py install for psutil 03:55:21 INFO - building 'psutil._psutil_linux' extension 03:55:21 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 03:55:22 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 03:55:22 INFO - building 'psutil._psutil_posix' extension 03:55:22 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 03:55:22 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 03:55:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:22 INFO - Successfully installed psutil 03:55:22 INFO - Cleaning up... 03:55:22 INFO - Return code: 0 03:55:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:55:22 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')]} 03:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:22 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')]} 03:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:22 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:22 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 03:55:22 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 03:55:22 INFO - Using env: {'DISPLAY': ':0', 03:55:22 INFO - 'HOME': '/home/cltbld', 03:55:22 INFO - 'LANG': 'en_US.UTF-8', 03:55:22 INFO - 'LANGUAGE': 'en_US:en', 03:55:22 INFO - 'LOGNAME': 'cltbld', 03:55:22 INFO - 'MAIL': '/var/mail/cltbld', 03:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:22 INFO - 'MOZ_NO_REMOTE': '1', 03:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:22 INFO - 'NO_EM_RESTART': '1', 03:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:22 INFO - 'PWD': '/builds/slave/test', 03:55:22 INFO - 'SHELL': '/bin/bash', 03:55:22 INFO - 'SHLVL': '1', 03:55:22 INFO - 'TERM': 'linux', 03:55:22 INFO - 'TMOUT': '86400', 03:55:22 INFO - 'USER': 'cltbld', 03:55:22 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:22 INFO - '_': '/tools/buildbot/bin/python'} 03:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:55:22 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 03:55:22 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 03:55:22 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 03:55:22 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 03:55:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:55:23 INFO - Installing collected packages: mozsystemmonitor 03:55:23 INFO - Running setup.py install for mozsystemmonitor 03:55:23 INFO - Successfully installed mozsystemmonitor 03:55:23 INFO - Cleaning up... 03:55:25 INFO - Return code: 0 03:55:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:55:25 INFO - Using env: {'DISPLAY': ':0', 03:55:25 INFO - 'HOME': '/home/cltbld', 03:55:25 INFO - 'LANG': 'en_US.UTF-8', 03:55:25 INFO - 'LANGUAGE': 'en_US:en', 03:55:25 INFO - 'LOGNAME': 'cltbld', 03:55:25 INFO - 'MAIL': '/var/mail/cltbld', 03:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:25 INFO - 'MOZ_NO_REMOTE': '1', 03:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:25 INFO - 'NO_EM_RESTART': '1', 03:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:25 INFO - 'PWD': '/builds/slave/test', 03:55:25 INFO - 'SHELL': '/bin/bash', 03:55:25 INFO - 'SHLVL': '1', 03:55:25 INFO - 'TERM': 'linux', 03:55:25 INFO - 'TMOUT': '86400', 03:55:25 INFO - 'USER': 'cltbld', 03:55:25 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:25 INFO - '_': '/tools/buildbot/bin/python'} 03:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:25 INFO - Downloading/unpacking jsonschema==2.5.1 03:55:25 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 03:55:25 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 03:55:25 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 03:55:25 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 03:55:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:55:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:55:26 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 03:55:26 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 03:55:26 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 03:55:26 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 03:55:26 INFO - Downloading functools32-3.2.3-2.tar.gz 03:55:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:55:26 INFO - warning: no files found matching '*.txt' 03:55:26 INFO - no previously-included directories found matching 'build' 03:55:26 INFO - no previously-included directories found matching 'dist' 03:55:26 INFO - no previously-included directories found matching '.git*' 03:55:26 INFO - Installing collected packages: jsonschema, functools32 03:55:27 INFO - Running setup.py install for functools32 03:55:27 INFO - warning: no files found matching '*.txt' 03:55:27 INFO - no previously-included directories found matching 'build' 03:55:27 INFO - no previously-included directories found matching 'dist' 03:55:27 INFO - no previously-included directories found matching '.git*' 03:55:27 INFO - Successfully installed jsonschema functools32 03:55:27 INFO - Cleaning up... 03:55:27 INFO - Return code: 0 03:55:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:55:27 INFO - Using env: {'DISPLAY': ':0', 03:55:27 INFO - 'HOME': '/home/cltbld', 03:55:27 INFO - 'LANG': 'en_US.UTF-8', 03:55:27 INFO - 'LANGUAGE': 'en_US:en', 03:55:27 INFO - 'LOGNAME': 'cltbld', 03:55:27 INFO - 'MAIL': '/var/mail/cltbld', 03:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:27 INFO - 'MOZ_NO_REMOTE': '1', 03:55:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:27 INFO - 'NO_EM_RESTART': '1', 03:55:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:27 INFO - 'PWD': '/builds/slave/test', 03:55:27 INFO - 'SHELL': '/bin/bash', 03:55:27 INFO - 'SHLVL': '1', 03:55:27 INFO - 'TERM': 'linux', 03:55:27 INFO - 'TMOUT': '86400', 03:55:27 INFO - 'USER': 'cltbld', 03:55:27 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:27 INFO - '_': '/tools/buildbot/bin/python'} 03:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:55:28 INFO - Cleaning up... 03:55:28 INFO - Return code: 0 03:55:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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')]} 03:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:28 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:28 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 03:55:28 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 03:55:28 INFO - Using env: {'DISPLAY': ':0', 03:55:28 INFO - 'HOME': '/home/cltbld', 03:55:28 INFO - 'LANG': 'en_US.UTF-8', 03:55:28 INFO - 'LANGUAGE': 'en_US:en', 03:55:28 INFO - 'LOGNAME': 'cltbld', 03:55:28 INFO - 'MAIL': '/var/mail/cltbld', 03:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:28 INFO - 'MOZ_NO_REMOTE': '1', 03:55:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:28 INFO - 'NO_EM_RESTART': '1', 03:55:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:28 INFO - 'PWD': '/builds/slave/test', 03:55:28 INFO - 'SHELL': '/bin/bash', 03:55:28 INFO - 'SHLVL': '1', 03:55:28 INFO - 'TERM': 'linux', 03:55:28 INFO - 'TMOUT': '86400', 03:55:28 INFO - 'USER': 'cltbld', 03:55:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:28 INFO - '_': '/tools/buildbot/bin/python'} 03:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:28 INFO - Downloading/unpacking blobuploader==1.2.4 03:55:28 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 03:55:28 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 03:55:28 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 03:55:28 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 03:55:29 INFO - Downloading blobuploader-1.2.4.tar.gz 03:55:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:55:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:55:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:55:30 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 03:55:30 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 03:55:30 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 03:55:30 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 03:55:30 INFO - Downloading docopt-0.6.1.tar.gz 03:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:55:30 INFO - Installing collected packages: blobuploader, requests, docopt 03:55:30 INFO - Running setup.py install for blobuploader 03:55:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:55:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:55:30 INFO - Running setup.py install for requests 03:55:30 INFO - Running setup.py install for docopt 03:55:30 INFO - Successfully installed blobuploader requests docopt 03:55:30 INFO - Cleaning up... 03:55:30 INFO - Return code: 0 03:55:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:30 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:30 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 03:55:30 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 03:55:30 INFO - Using env: {'DISPLAY': ':0', 03:55:30 INFO - 'HOME': '/home/cltbld', 03:55:30 INFO - 'LANG': 'en_US.UTF-8', 03:55:30 INFO - 'LANGUAGE': 'en_US:en', 03:55:30 INFO - 'LOGNAME': 'cltbld', 03:55:30 INFO - 'MAIL': '/var/mail/cltbld', 03:55:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:30 INFO - 'MOZ_NO_REMOTE': '1', 03:55:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:30 INFO - 'NO_EM_RESTART': '1', 03:55:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:30 INFO - 'PWD': '/builds/slave/test', 03:55:30 INFO - 'SHELL': '/bin/bash', 03:55:30 INFO - 'SHLVL': '1', 03:55:30 INFO - 'TERM': 'linux', 03:55:30 INFO - 'TMOUT': '86400', 03:55:30 INFO - 'USER': 'cltbld', 03:55:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:30 INFO - '_': '/tools/buildbot/bin/python'} 03:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:31 INFO - Running setup.py (path:/tmp/pip-_Ev5RM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:31 INFO - Running setup.py (path:/tmp/pip-vcFAVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:31 INFO - Running setup.py (path:/tmp/pip-iGi5Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:31 INFO - Running setup.py (path:/tmp/pip-sCnouJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:31 INFO - Running setup.py (path:/tmp/pip-YmUnJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:31 INFO - Running setup.py (path:/tmp/pip-oYQF8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:31 INFO - Running setup.py (path:/tmp/pip-NS_s0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:31 INFO - Running setup.py (path:/tmp/pip-WxFpqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:31 INFO - Running setup.py (path:/tmp/pip-ZlP2dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:31 INFO - Running setup.py (path:/tmp/pip-6uLAGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:31 INFO - Running setup.py (path:/tmp/pip-oMp2XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:31 INFO - Running setup.py (path:/tmp/pip-7O7jVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:32 INFO - Running setup.py (path:/tmp/pip-5eVs0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:32 INFO - Running setup.py (path:/tmp/pip-zMPbLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:32 INFO - Running setup.py (path:/tmp/pip-C63Csr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:32 INFO - Running setup.py (path:/tmp/pip-GslLWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:32 INFO - Running setup.py (path:/tmp/pip-Ned_VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:55:32 INFO - Running setup.py install for manifestparser 03:55:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:55:32 INFO - Running setup.py install for mozcrash 03:55:32 INFO - Running setup.py install for mozdebug 03:55:32 INFO - Running setup.py install for mozdevice 03:55:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:55:32 INFO - Running setup.py install for mozfile 03:55:32 INFO - Running setup.py install for mozhttpd 03:55:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:55:32 INFO - Running setup.py install for mozinfo 03:55:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozInstall 03:55:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozleak 03:55:33 INFO - Running setup.py install for mozlog 03:55:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for moznetwork 03:55:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozprocess 03:55:33 INFO - Running setup.py install for mozprofile 03:55:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozrunner 03:55:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozscreenshot 03:55:33 INFO - Running setup.py install for moztest 03:55:34 INFO - Running setup.py install for mozversion 03:55:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:55:34 INFO - Cleaning up... 03:55:34 INFO - Return code: 0 03:55:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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')]} 03:55:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:34 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:34 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 03:55:34 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 03:55:34 INFO - Using env: {'DISPLAY': ':0', 03:55:34 INFO - 'HOME': '/home/cltbld', 03:55:34 INFO - 'LANG': 'en_US.UTF-8', 03:55:34 INFO - 'LANGUAGE': 'en_US:en', 03:55:34 INFO - 'LOGNAME': 'cltbld', 03:55:34 INFO - 'MAIL': '/var/mail/cltbld', 03:55:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:34 INFO - 'MOZ_NO_REMOTE': '1', 03:55:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:34 INFO - 'NO_EM_RESTART': '1', 03:55:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:34 INFO - 'PWD': '/builds/slave/test', 03:55:34 INFO - 'SHELL': '/bin/bash', 03:55:34 INFO - 'SHLVL': '1', 03:55:34 INFO - 'TERM': 'linux', 03:55:34 INFO - 'TMOUT': '86400', 03:55:34 INFO - 'USER': 'cltbld', 03:55:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:34 INFO - '_': '/tools/buildbot/bin/python'} 03:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:34 INFO - Running setup.py (path:/tmp/pip-z5Znvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:34 INFO - Running setup.py (path:/tmp/pip-mn_ACZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:34 INFO - Running setup.py (path:/tmp/pip-Z38ckg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:34 INFO - Running setup.py (path:/tmp/pip-fvHW7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:34 INFO - Running setup.py (path:/tmp/pip-zXKhcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:34 INFO - Running setup.py (path:/tmp/pip-uDdmT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:34 INFO - Running setup.py (path:/tmp/pip-sfH5kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:34 INFO - Running setup.py (path:/tmp/pip-7WjjmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:34 INFO - Running setup.py (path:/tmp/pip-Wa9sLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:34 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)) 03:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:34 INFO - Running setup.py (path:/tmp/pip-wFqrxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:35 INFO - Running setup.py (path:/tmp/pip-pLI4cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:35 INFO - Running setup.py (path:/tmp/pip-sfxER6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:35 INFO - Running setup.py (path:/tmp/pip-g2uQo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:35 INFO - Running setup.py (path:/tmp/pip-OPu47K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:35 INFO - Running setup.py (path:/tmp/pip-y0Qe24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:35 INFO - Running setup.py (path:/tmp/pip-JE6mg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:35 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)) 03:55:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:35 INFO - Running setup.py (path:/tmp/pip-iy8rIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:35 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)) 03:55:35 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)) 03:55:35 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)) 03:55:35 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)) 03:55:35 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)) 03:55:35 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)) 03:55:35 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)) 03:55:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:36 INFO - Downloading blessings-1.6.tar.gz 03:55:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:55:36 INFO - Installing collected packages: blessings 03:55:36 INFO - Running setup.py install for blessings 03:55:36 INFO - Successfully installed blessings 03:55:36 INFO - Cleaning up... 03:55:36 INFO - Return code: 0 03:55:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:55:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:37 INFO - Reading from file tmpfile_stdout 03:55:37 INFO - Current package versions: 03:55:37 INFO - argparse == 1.2.1 03:55:37 INFO - blessings == 1.6 03:55:37 INFO - blobuploader == 1.2.4 03:55:37 INFO - docopt == 0.6.1 03:55:37 INFO - functools32 == 3.2.3-2 03:55:37 INFO - jsonschema == 2.5.1 03:55:37 INFO - manifestparser == 1.1 03:55:37 INFO - mozInstall == 1.13 03:55:37 INFO - mozcrash == 1.0 03:55:37 INFO - mozdebug == 0.1 03:55:37 INFO - mozdevice == 0.51 03:55:37 INFO - mozfile == 1.2 03:55:37 INFO - mozhttpd == 0.7 03:55:37 INFO - mozinfo == 0.10 03:55:37 INFO - mozleak == 0.1 03:55:37 INFO - mozlog == 3.5 03:55:37 INFO - moznetwork == 0.27 03:55:37 INFO - mozprocess == 0.25 03:55:37 INFO - mozprofile == 0.28 03:55:37 INFO - mozrunner == 6.13 03:55:37 INFO - mozscreenshot == 0.1 03:55:37 INFO - mozsystemmonitor == 0.3 03:55:37 INFO - moztest == 0.8 03:55:37 INFO - mozversion == 1.4 03:55:37 INFO - psutil == 3.1.1 03:55:37 INFO - requests == 1.2.3 03:55:37 INFO - wsgiref == 0.1.2 03:55:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:37 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')]} 03:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:37 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')]} 03:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:37 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:37 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 03:55:37 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 03:55:37 INFO - Using env: {'DISPLAY': ':0', 03:55:37 INFO - 'HOME': '/home/cltbld', 03:55:37 INFO - 'LANG': 'en_US.UTF-8', 03:55:37 INFO - 'LANGUAGE': 'en_US:en', 03:55:37 INFO - 'LOGNAME': 'cltbld', 03:55:37 INFO - 'MAIL': '/var/mail/cltbld', 03:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:37 INFO - 'MOZ_NO_REMOTE': '1', 03:55:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:37 INFO - 'NO_EM_RESTART': '1', 03:55:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:37 INFO - 'PWD': '/builds/slave/test', 03:55:37 INFO - 'SHELL': '/bin/bash', 03:55:37 INFO - 'SHLVL': '1', 03:55:37 INFO - 'TERM': 'linux', 03:55:37 INFO - 'TMOUT': '86400', 03:55:37 INFO - 'USER': 'cltbld', 03:55:37 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:37 INFO - '_': '/tools/buildbot/bin/python'} 03:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 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)) 03:55:37 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:55:37 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 03:55:37 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 03:55:37 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 03:55:37 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 03:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:55:38 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)) 03:55:38 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)) 03:55:38 INFO - Installing collected packages: simplejson 03:55:38 INFO - Running setup.py install for simplejson 03:55:38 INFO - building 'simplejson._speedups' extension 03:55:38 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 03:55:39 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 03:55:39 INFO - Successfully installed simplejson 03:55:39 INFO - Cleaning up... 03:55:39 INFO - Return code: 0 03:55:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:39 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 0x1d611f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f29b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ce9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, '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': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', '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 03:55:39 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 03:55:39 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 03:55:39 INFO - Using env: {'DISPLAY': ':0', 03:55:39 INFO - 'HOME': '/home/cltbld', 03:55:39 INFO - 'LANG': 'en_US.UTF-8', 03:55:39 INFO - 'LANGUAGE': 'en_US:en', 03:55:39 INFO - 'LOGNAME': 'cltbld', 03:55:39 INFO - 'MAIL': '/var/mail/cltbld', 03:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:39 INFO - 'MOZ_NO_REMOTE': '1', 03:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:39 INFO - 'NO_EM_RESTART': '1', 03:55:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:39 INFO - 'PWD': '/builds/slave/test', 03:55:39 INFO - 'SHELL': '/bin/bash', 03:55:39 INFO - 'SHLVL': '1', 03:55:39 INFO - 'TERM': 'linux', 03:55:39 INFO - 'TMOUT': '86400', 03:55:39 INFO - 'USER': 'cltbld', 03:55:39 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:39 INFO - '_': '/tools/buildbot/bin/python'} 03:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:55:39 INFO - Cleaning up... 03:55:39 INFO - Return code: 0 03:55:39 INFO - Running post-action listener: _resource_record_post_action 03:55:39 INFO - Running post-action listener: _start_resource_monitoring 03:55:39 INFO - Starting resource monitoring. 03:55:39 INFO - [mozharness: 2017-09-25 10:55:39.865655Z] Finished create-virtualenv step (success) 03:55:39 INFO - [mozharness: 2017-09-25 10:55:39.866184Z] Running install step. 03:55:39 INFO - Running pre-action listener: _resource_record_pre_action 03:55:39 INFO - Running main action method: install 03:55:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:40 INFO - Reading from file tmpfile_stdout 03:55:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:55:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:55:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:55:40 INFO - Reading from file tmpfile_stdout 03:55:40 INFO - Output received: 03:55:40 INFO - Usage: mozinstall [options] installer 03:55:40 INFO - Options: 03:55:40 INFO - -h, --help show this help message and exit 03:55:40 INFO - -d DEST, --destination=DEST 03:55:40 INFO - Directory to install application into. [default: 03:55:40 INFO - "/builds/slave/test"] 03:55:40 INFO - --app=APP Application being installed. [default: firefox] 03:55:40 INFO - mkdir: /builds/slave/test/build/application 03:55:40 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'] 03:55:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:55:58 INFO - Reading from file tmpfile_stdout 03:55:58 INFO - Output received: 03:55:58 INFO - /builds/slave/test/build/application/firefox/firefox 03:55:58 INFO - Running post-action listener: _resource_record_post_action 03:55:58 INFO - [mozharness: 2017-09-25 10:55:58.021911Z] Finished install step (success) 03:55:58 INFO - [mozharness: 2017-09-25 10:55:58.022166Z] Running setup-mitmproxy step. 03:55:58 INFO - Running pre-action listener: _resource_record_pre_action 03:55:58 INFO - Running main action method: setup_mitmproxy 03:55:58 INFO - Skipping: mitmproxy is not required 03:55:58 INFO - Running post-action listener: _resource_record_post_action 03:55:58 INFO - [mozharness: 2017-09-25 10:55:58.023302Z] Finished setup-mitmproxy step (success) 03:55:58 INFO - [mozharness: 2017-09-25 10:55:58.023524Z] Running run-tests step. 03:55:58 INFO - Running pre-action listener: _resource_record_pre_action 03:55:58 INFO - Running pre-action listener: _set_gcov_prefix 03:55:58 INFO - Running main action method: run_tests 03:55:58 WARNING - Try message not found. 03:55:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:55:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:55:58 INFO - Python 2.7.3 03:55:58 INFO - Return code: 0 03:55:58 INFO - grabbing minidump binary from tooltool 03:55: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')]} 03:55:58 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 0x20cbd60>, '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 0x20cca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ccef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:55:58 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 03:55:58 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 03:55:58 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 03:55:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:55:58 INFO - Return code: 0 03:55:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:55:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:55:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:55:58 INFO - ENV: RUST_BACKTRACE is now full 03:55:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:55:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:55:58 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 03:55:58 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 03:55:58 INFO - Using env: {'DISPLAY': ':0', 03:55:58 INFO - 'HOME': '/home/cltbld', 03:55:58 INFO - 'LANG': 'en_US.UTF-8', 03:55:58 INFO - 'LANGUAGE': 'en_US:en', 03:55:58 INFO - 'LOGNAME': 'cltbld', 03:55:58 INFO - 'MAIL': '/var/mail/cltbld', 03:55:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:55:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:55:58 INFO - 'MOZ_ACCELERATED': '1', 03:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:58 INFO - 'MOZ_NO_REMOTE': '1', 03:55:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:55:58 INFO - 'MOZ_WEBRENDER': '1', 03:55:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:55:58 INFO - 'NO_EM_RESTART': '1', 03:55:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:58 INFO - 'PWD': '/builds/slave/test', 03:55:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:55:58 INFO - 'RUST_BACKTRACE': 'full', 03:55:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:55:58 INFO - 'SHELL': '/bin/bash', 03:55:58 INFO - 'SHLVL': '1', 03:55:58 INFO - 'TERM': 'linux', 03:55:58 INFO - 'TMOUT': '86400', 03:55:58 INFO - 'USER': 'cltbld', 03:55:58 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:58 INFO - '_': '/tools/buildbot/bin/python'} 03:55:58 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/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 03:55:58 INFO - mozversion application_buildid: 20170925095842 03:55:58 INFO - mozversion application_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 03:55:58 INFO - mozversion application_display_name: Nightly 03:55:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:55:58 INFO - mozversion application_name: Firefox 03:55:58 INFO - mozversion application_remotingname: firefox 03:55:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:55:58 INFO - mozversion application_vendor: Mozilla 03:55:58 INFO - mozversion application_version: 58.0a1 03:55:58 INFO - mozversion platform_buildid: 20170925095842 03:55:58 INFO - mozversion platform_changeset: 5f3f19824efa14cc6db546baf59c54a0fc15ddc9 03:55:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:55:58 INFO - mozversion platform_version: 58.0a1 03:55:58 INFO - using testdate: 1506336958 03:55:58 INFO - actual date: 1506336958 03:55:58 INFO - starting webserver on 'localhost:56273' 03:55:58 INFO - SUITE-START | Running 2 tests 03:55:58 INFO - TEST-START | dromaeo_css 03:55:58 INFO - Initialising browser for dromaeo_css test... 03:55:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56273/getInfo.html -profile /tmp/tmptTtlE7/profile 03:55:58 INFO - TEST-INFO | started process 14473 (/builds/slave/test/build/application/firefox/firefox http://localhost:56273/getInfo.html) 03:56:05 INFO - TEST-INFO | 14473: exit 0 03:56:06 INFO - Browser initialized. 03:56:06 INFO - Running cycle 1/1 for dromaeo_css test... 03:56:06 INFO - Using env: {'DISPLAY': ':0', 03:56:06 INFO - 'HOME': '/home/cltbld', 03:56:06 INFO - 'JSGC_DISABLE_POISONING': '1', 03:56:06 INFO - 'LANG': 'en_US.UTF-8', 03:56:06 INFO - 'LANGUAGE': 'en_US:en', 03:56:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:56:06 INFO - 'LOGNAME': 'cltbld', 03:56:06 INFO - 'MAIL': '/var/mail/cltbld', 03:56:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:56:06 INFO - 'MOZ_ACCELERATED': '1', 03:56:06 INFO - 'MOZ_CRASHREPORTER': '1', 03:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:56:06 INFO - 'MOZ_NO_REMOTE': '1', 03:56:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:06 INFO - 'MOZ_WEBRENDER': '1', 03:56:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:06 INFO - 'NO_EM_RESTART': '1', 03:56:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:06 INFO - 'PWD': '/builds/slave/test', 03:56:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:06 INFO - 'RUST_BACKTRACE': 'full', 03:56:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:56:06 INFO - 'SHELL': '/bin/bash', 03:56:06 INFO - 'SHLVL': '1', 03:56:06 INFO - 'TERM': 'linux', 03:56:06 INFO - 'TMOUT': '86400', 03:56:06 INFO - 'USER': 'cltbld', 03:56:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 03:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:06 INFO - '_': '/tools/buildbot/bin/python'} 03:56:06 INFO - TEST-INFO | started process 14718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptTtlE7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:06 INFO - PID 14718 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:56:07 INFO - PID 14718 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:56:07 INFO - PID 14718 | 03:56:07 INFO - PID 14718 | (/builds/slave/test/build/application/firefox/firefox:14777): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:56:07 INFO - PID 14718 | 03:56:17 INFO - PID 14718 | RSS: Main: 191545344 03:56:17 INFO - PID 14718 | 03:59:15 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56273/tests/dromaeo/cssquery-ext.html) 03:59:16 INFO - PID 14718 | RSS: Main: 196988928 03:59:16 INFO - PID 14718 | 04:02:19 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-ext.html (next: http://localhost:56273/tests/dromaeo/cssquery-jquery.html) 04:02:19 INFO - PID 14718 | RSS: Main: 196669440 04:02:19 INFO - PID 14718 | 04:05:21 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56273/tests/dromaeo/cssquery-mootools.html) 04:05:22 INFO - PID 14718 | RSS: Main: 197140480 04:05:22 INFO - PID 14718 | 04:08:24 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56273/tests/dromaeo/cssquery-prototype.html) 04:08:25 INFO - PID 14718 | RSS: Main: 195571712 04:08:25 INFO - PID 14718 | 04:11:28 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56273/tests/dromaeo/cssquery-yui.html) 04:11:29 INFO - PID 14718 | RSS: Main: 199245824 04:11:29 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | Cycle 1(1): loaded http://localhost:56273/tests/dromaeo/cssquery-yui.html (next: http://localhost:56273/tests/dromaeo/cssquery-dojo.html) 04:14:31 INFO - PID 14718 | RSS: Main: 199458816 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | __start_tp_report 04:14:31 INFO - PID 14718 | _x_x_mozilla_page_load 04:14:31 INFO - PID 14718 | _x_x_mozilla_page_load_details 04:14:31 INFO - PID 14718 | |i|pagename|runs| 04:14:31 INFO - PID 14718 | |0;dojo.html;3080.5841211436455;3118.501039833627;3120.3291292372137;3127.7966932149416;3132.15431833405;6154.138420621117;6217.471514921232;6278.591891527053;6309.68451577421;6312.274088479826;6225.875482490347;6238.407351301619;6249.125122482856;6268.65522396268;6313.842153946153;5724.255846739918;5748.856278593027;5782;5785.190073389729;5830.970845145789;5805.158252053449;5811.651300921958;5842.64944103353;5878.177055212273;5934.703264836762;5805;5867.2372591563035;5877.471027607515;5890.440408161217;5965.88068238635;5586.776528938837;5701.600887937846;5793.536517078624;5805.36141024487;5931.139984702216;5175.027664169174;5649.179221501013;5687.345955215121;5785.421457854223;5807.622504537234;6221.96889015552;6310.684465776692;6321.525885558602;6333.145025421555;6372.044193370985;6535.281119076898;6601.174853143357;6678.332166783282;6686.3313668632745;6788.626625535593;6790.456763458961;6824.897626535606;6872.862542749167;6882.965585172092;6903.447724182103;6240.095186198021;6292.370762923717;6315.3684631536935;6341.587796793194;6348.77779277723;6837.316268373223;6841.521093523405;6895.931040689629;6970.965145174242;6982.057422248033;6469.061986012096;6473.870522589522;6556.541042127005;6580.21037475506;6588.769393071316;6693.598384096969;6694.464442844658;6710.630915299607;6787.864242715118;6882.4149947254045;6770.492213084039;6785.287544807823;6840.692168852314;6845.965770171118;6853.657317134123;6746.291639377893;6768.76309329181;6793.558418702769;6850.588964662136;6893.551919125241;5181.5077567631015;5278.2346559749385;5287.41838397776;5332.7067011313975;5383.2194331821665;4672.836450724209;4677.228257337501;4696.9060618787435;4716.740579268173;4728.196206644879;5768;5808.738606762706;5835.328937172195;5837;5917.142014407887;6707.3628005339415;6778.559393639398;6858.6570671466225;6877.552959057645;6897.137857767779;5814.651120932757;5847.06446968483;5896.970515147397;5911.172435858984;5916.083007133901;5690.886182276331;5791.102379131242;5798.275215598051;5881.382454842265;5887.61730487517;6489.286178520359;6608.008798680236;6611.570247933869;6677.699503522354;6678.232003319583;6785.355391237825;6811.182658080963;6822.181338239443;6870.587764734132;6896.413804826646;6412.711427985659;6519.608823470607;6541.967290163519;6542.411182993554;6572.14562106923;6610.735570577123;6655.700493477708;6675.0654908313845;6676.866462670817;6688.765893193715;6654.234763002123;6802.319768023158;6805.4895882808;6826;6856.588604683735;6500.609963402211;6515.12045873801;6577.6053436792035;6613.14029176204;6620.966895165493;6400.55196136266;6456.677166141768;6495.1556297681;6562.606243625398;6598.241202261609;6409.070684750778;6473.935260647333;6496.935030649633;6511.055896894829;6531.052997315268;6509.576877502947;6682.398584127452;6692.732290708317;6699.196096468455;6703.229128650072;6327.17746692927;6338.112664227104;6359.650219237987;6431.742730290736;6450.903236451363 04:14:31 INFO - PID 14718 | |1;ext.html;862.710991817741;873.7509809704236;878.6529320918238;889.6574818694804;911.2846415563783;40712;41884.790576047155;42090.36864447032;42174.15651686965;42240.57759422401;38558.80720596393;38835;38960.61039389605;38985.41521877174;39171.80414097926;7127.2160062393095;7139.5359301645485;7233.529820561673;7255.34701876831;7273.454490913176;7029.5430796998035;7191.280871912793;7220.747273845416;7232.8191795205275;7243.167035790872;7226;7805.60971951403;8128.227818357246;8132.512049277033;8133.633986470623;8015.038195416558;8036.116027236999;8087.353011759074;8098.028236611615;8111.5133092014075;7687.730929417499;7938.24586664266;7954.323882469997;7982.121966583672;7982.560959147303;37577.81211093954;37908.62091379106;37953.0511737206;38057.048573785876;38121.23757524834;3499.737519686034;3508.3158784037123;3518.4194607889667;3538.9646103538894;3541.008517615072;31708;31740.682593174235;32094.19764505906;32097.679023209937;32099;5152.587792976553;5175.585953123741;5192.273081768518;5215.269862219292;5262.815801446931;187179;187806;190071;191375;191616;48495.27257091147;48522;48670.5132948666;48998;49025.754871225414;51802.22296665553;51825.48174518206;51973;52018.47981520136;52047.73976130095;16205.837941620668;16427.342906283615;16446.588835279214;16515.6696866062;16521.91739041297;13224.338783060808;13230.147779310904;13344.666383340495;13364.73270534584;13376.799348009788;14600.926995365167;14779.113253204841;14911.70176596462;14964.775528367083;15027;5598.300212473441;5716.828050249772;5795.420457954255;5809.2738407699035;5840.787566838222;984.3749219245831;991.9454430006275;993.4684943555163;998.9850152247722;1024.1704219582161;22678.546429071324;22795.430114246945;22972.310830675106;22972.65541016886;23492.177773777836;1085.8045551800594;1097.5445190246119;1108.8281316395971;1113.9554417823197;1120.6301920366259;12296.631101066982;12344.259344439362;12345.753084938431;12386.132970691964;12488.438020289108;219253;220040;221966;222061;222284;45458;53879;54236.72881635645;54597.72701136469;54712.726436367564;1307.4247331174254;1434.7991281220638;1456.4975083596141;1457.9052361596462;1471.565888063024;27787.749551269837;27825.721742783124;28118.297042573282;28224.71775282303;28520.57219141673;39865;40069.79965100156;40105.39841902432;40138.79930600328;40158.79920600378;199202;199516;200071.99964000087;200661;201638;36127.27745445129;37159;37277.6272237285;37552;37582.24835503329;43772.34341484817;43929.34105988477;44514.55485445104;44824.55175448204;44839.32741008822;32251;32314.192145196557;32412.675873241908;32542.674573253964;32770;32810.1797455056;32978.67021329852;33071.33857322888;33076.83461582677;33148.50277245795;36171.63828361788;37507.2498550022;37924.05189870275;38029.2394152121;38061.42907856329;30875.382492350476;31736.841315793274;31935.041948741575;32095.518567221978;32339.35321293514;23294;23344.416389590395;23532.398994299507;23559.882200588887;23625.52748945046 04:14:31 INFO - PID 14718 | |2;jquery.html;575.3096284458651;589.7847285740706;600.7116584039661;601.179390132469;610.9358517355678;22127.44681382966;22482.325530234077;22828.08687652496;22913.4271643209;22918.083276668935;23645.40886477839;24760;24782.380440488996;24946.625800613005;25336.859841307138;6381.074744162093;6410.839729006789;6438.130852334945;6469.4177524022825;6490.383413575702;6373.139626150457;6389.265234498046;6450.451711604519;6485;6492.253390860064;6200.813975580717;6439.774607888725;6557.836054098661;6568.835779105537;6571.441427478646;6550.967245163767;6567.93432065678;6576.145101136869;6588.176477940257;6602.834929126762;6133.6626485543275;6147.600405973598;6158.291796443422;6169.290531588835;6176.197094377702;28185.859070704515;28988.855055724798;29125.126246212647;29181.56227656587;29374.706252937413;1995.8902260375673;2087.4363921741196;2088.5092003379195;2092.8325733941247;2099.958000839975;18664.813351866444;19195.712064319046;19244.134013969266;19471.221151153935;19570.51073723154;2470.740572239907;2503.249025292405;2518.42076322445;2536.7970562354967;2542.580474221751;124549;130337.34831325877;131085;138763;143694.28152859272;14638.59383407399;14691.914697913344;14985.751857748006;15006.774898376532;15394.692106158038;23266.06935722586;23500.882495587754;23501.764982350298;23677.526449471257;23703.170389036302;9870.976449827767;9955.352902061342;9988.850167247498;10009.399436033862;10073.1437827784;9047.185753282105;9224.262059035262;9248.398854074598;9276;9318;8916.420432671855;8951.73144805657;8998.505082220541;9069.183773460289;9287.58205880737;2795.9021434249703;2815.8310501369983;2819.323362393052;2825.3360460291815;2843.957340639892;947.7109481608038;971.8493633486821;973.3186769261404;978.6770365779275;980.0983095552109;18409.263629454672;18901.62196756057;18959.24163033491;19051.047447627563;19102.14040368159;1017.9949100254452;1023.3297190340339;1034.7051090439188;1040.1834559870617;1050.658535975811;10469.319494233003;10827.729306767236;10848.566057357617;10890.836637450286;10903.375495845992;449626;459564;461697;469040;471242.5751484951;109533.45233273783;110175;113235;113294.43352783183;113500;1166.7258194324327;1215.617080619602;1237.406045098358;1264.8039553869348;1276.789329760579;20079.92806474185;21290.041948112063;21324.680129797754;21404;21553.35339939804;57380.42619573918;61857;61891.381086188565;64040.35959640344;64336.35663643491;76789.61605191938;77053.61473192822;78720.60639696765;79514.6024269898;80668;19603.411897643095;19632.90183549073;19729.506762331057;19979.60040799205;19982.20071197136;22090.77909220887;22319.553608927406;22389.32832015042;22600.773992259867;22638.434039148495;17748.11259436997;17864.285428582712;18202.271909124018;18243.45269641913;18359.90820045891;15532.611684707972;16533.421330253383;16548.338066477205;16754.4135955241;16798.664026719154;18386.81613183851;18439.078046097373;18452.446426607225;18598.721019184453;18621.720674189626;18377.90811045936;19335.613287734446;19428.222871085;19514.21943122259;19529.414117576496;17382.82617173812;17629.471115866545;17668.91165544164;17823.73264401009;17898.194581244137 04:14:31 INFO - PID 14718 | |3;mootools.html;1279.7312564361484;1362.5163067111175;1385.501219560958;1389.9235542045183;1399.8600139985997;15744.134072626;16152.111633860133;16156.434524791635;16275.104869232187;16291.51125466235;15830.525084247462;15833.366665333373;15901.681966360666;15938.043717376937;16013;2824.985875070632;2857.171420288119;2885.0911962731775;2889.523228667267;2895.884164633412;2641.7622413982735;2749.6975332713387;2757.0074773081674;2775.292300463389;2777.4445110977786;2704.2428120126297;2783.902563410281;2790.539560972437;2794.147784925597;2801.887924483018;2830.5329620612574;2866.0398766413277;2883.5818806272973;2891.942161156765;2898.5797059426372;2738.288045108286;2758.972410275912;2782.1375373634037;2783.7216278372202;2790.916272511828;15017.549473515812;15489;15496.380144794195;15535.145566993811;15582.922085389613;8205.425620206588;8400.495970241805;8441.409101362908;8446.746597602081;8474.279686226679;5556.777728890839;5714.51426628737;5742.2247996520355;5821.592488525805;5876.55925805562;2019.2730616977876;2024.5647185854964;2026.5237669147887;2041.550858811074;2063.8968051597512;26116.73883261162;27504.58743118855;27790.16629501098;27885.442291154064;28090.157295281173;6999.895001574981;7096.97395646464;7203.207647158808;7220.4584656151;7276.163241227327;17315;17395.826041739674;17409.47771566855;17412.564685882953;17526.036068016125;7718.614069296513;8135.389845761473;8156.918430815735;8213.466124701876;8240.423170378015;7466.25337466249;7557.5465472071855;7615.162332143571;7633.465657404039;7705.8844117338285;5275.630705850554;5420.620556561003;5442.482964118402;5449.64577302474;5479.863003424946;1450.1371683848095;1612.7661489084016;1620.3194658243567;1652.339064374236;1662.5178698177392;775.3293401207915;900.221308568097;912.5847739278612;915.8809354783835;926.9664324278516;7511.511751736229;7592.305770671996;7662.4253181011645;7668.693252269958;7802.414818888606;999.8052327468605;1005.3766664667953;1008.9243306752023;1014.5992333028413;1026.312370711625;4565.178267911808;4631.884202894955;4664.113818374498;4680.7191568506;4770.499097594772;22371.888140559193;22455.32634020982;22473.88763056174;22698.546029079327;22828;21788;22658.320250392513;22675.546489070457;22689.319320420414;22744.65883011756;11132.833007505056;11877.109216808774;11904.940475297568;12055.87944120565;12067.879321206676;8423.157684231528;8447.324214062737;8529.317654587741;8715.607797648994;8747.781305467413;18379.81620183781;18626.068696565388;18824.15291311867;18964.43106706801;19336.903315483334;18884.150213240162;19906.50233744168;19954.30159944453;20046.39860804178;20344;11078.5568577256;11259.549618015188;11289.209755317377;11290.830637540279;11381.772364552497;13995;14034.719305614035;14293;14387.712245755236;14492.492762753252;11284.09727221804;11591.362475063952;11699.941500292443;11721;11801.468933897824;11093;11223.887761122285;11350.602728904449;11384.316940983568;11482.77034459323;10035.14701250402;10165.085142337226;10175.949120254352;10206;10269;7455.776326710207;7593.088829340514;7599.544027358376;7612.505187162817;7622.847543049219;7569.583672898044;7586.506877052974;7652.158262591;7784.922150778646;7820.726274580363 04:14:31 INFO - PID 14718 | |4;prototype.html;506.26591442408494;528.7144941731465;539.8824433422815;548.3228213156751;551.5504863536219;6304.653244071575;6382.585131966419;6418.775342863;6433.871322573552;6456.967715161423;6213.56505044647;6217.285012223596;6217.658028808413;6259.186305780254;6267.529935254863;5162.328897243353;5242.501962313593;5249.947500525003;5260.526552610264;5292.788288468457;5184.688918664873;5235.424103348629;5260.237265596486;5288.15389537674;5299.33758280215;4852.878678033042;4976.118627470579;4981.850544483671;5071.340725705653;5197.272381866542;4984.875378115558;4997.350344455216;5025.994801039807;5040.47075057121;5068.518490743373;4493.932591011137;4504.144212599595;4533.184026875194;4535.142859998213;4545.818167273338;5983.132445795379;6098.634081955052;6157.3534778848025;6161.815145545641;6164.815055548341;5716.742746576414;5765.1928729977835;5767.394423585547;5769.711514424262;5820.35976042635;6525.984566590933;6559.639219842907;6564.507661925375;6582.308857569934;6651;4943.937053533472;4958.925616115761;4970.329005584236;4972.825951091694;4999.8250061248045;6680.933190668128;6778.864422711567;6786.898196527056;6911.481638877054;6945.583265004116;5007.172382080515;5033.458103657629;5054.974725126424;5076.822311219164;5092.796288148433;5067.023860694332;5085.771140298695;5164.561012313995;5167.819126330561;5175.223716442564;5739;5744.368119506851;5773.507028247934;5795.695968407074;5817.185594016841;6601.834954126185;6644.867102658017;6683.699233534503;6694.364035416725;6713.395794378529;1766.3905952446385;1767.3637490503409;1785.69643160663;1788.2936240185054;1789.8634367176937;1170.2919733561243;1219.1222319929636;1240.5472002718943;1255.6735248835187;1260.602910083321;5615;5697.202391665171;5706.771729130788;5707.315122185364;5805.332386775575;6730.226024007302;6749.156355455568;6801.35387138231;6815.2162501312;6841.247462779091;5675.687837168913;5733;5769.567282453832;5785.218995435563;5836;3547.255076433926;3556.8399422026073;3560.2167523144867;3560.590532088843;3584.2831433713363;6094.75620975156;6402.615843049339;6461.483081353481;6485.8702825944165;6512.446442052384;6736.865262694719;6782.559133656297;6837.1111755471475;6840.247572766992;6939.895901561481;6390.45681117101;6392.497763025593;6492.285848556749;6516.5112616553;6555.868882622321;6684.63234522106;6708.6310252936555;6738.663066846736;6803.183617965876;6862.176538815374;6656.201255849329;6666.699998499982;6778.288279730755;6795.52431329822;6815.216250131299;6514.176030711408;6564.9671751640935;6588;6597.50518711089;6621.106150669791;4910.410750709938;4938.320970869921;5023.271625614192;5027.924581131213;5042.823501177441;6405.839854003687;6491.578047427091;6680.5991640501725;6727.8990815138795;6774.593524388552;6449.516286278454;6475.449586785176;6494.740210391531;6500.284968653539;6522.184726909137;6387.125793393085;6411;6447.387498187664;6499;6521.184851893513;6277;6326.177596912372;6344.350468878032;6373.681315934277;6401.295857455583;6505.869882602226;6554.344565543408;6561.245456772342;6588.769393071219;6654.208969541922;6167.6916154193;6245;6262.405071518198;6308.148399965946;6394 04:14:31 INFO - PID 14718 | |5;yui.html;888.6711916590863;903.5437104262348;919.2783664823115;920.8940971788246;935.752025713186;19846.801531984693;20637.793622063793;20679;20685.276015339467;20726.792732072692;18807;18965.24139034437;19408;19510.31713890014;19643.31248406306;956.0296299256244;957.9042095790435;958.8705524754173;960.1358777100595;963.0225321298888;981.2591493422456;985.635314933472;986.7730422002944;989.198749013298;993.2103977337997;992.2558081438921;1021.5045702834119;1031.1544533482547;1038.5638032026568;1040.7866387390604;1035.1460045462447;1035.1511760356511;1036.6786296248188;1041.5261056219479;1041.8437234414823;704.8061783009662;731.4185222747928;746.2537462537463;751.2600088912417;755.4334249313015;20838;20954;21046.158153673834;21046.894765526227;21101.68347474789;19403.80596194048;19417;20163.697544536844;20239;20304.593908121755;17301.394451194275;17475.82524174755;17726.645467090588;17737.37919172823;17942.461726148238;3774.7168962327937;3817.48463957365;3824.7896365699876;3826.751261168043;3836.635519625631;142299;143050;145402.27298863544;147063;147227;16405.261763220682;16628.3348666052;16680.082595457123;16820.411285604947;16834.747478787827;30649.540256895718;30778.384432311675;32078.51882221722;32517.51223731646;32838.3432331352;8839.337049721296;8849.734507964642;8869.246114080375;8924.732258032269;8925.866112008325;3946.8421263149153;3962.385830196324;3966.2662407454714;4040.1919616076316;4081.3673880548563;5486.423925487767;5489.752961116759;5524.475174858382;5577.553795696334;5587.3015873015875;964.7298756348234;998.2562990571926;1009.3187098708343;1015.3501758874327;1019.6431249062917;688.6315821035722;700.8948657701386;713.8822094354373;715.4348065028673;719.3238355945499;11806.645800625995;11806.88193118075;12189.817152742717;12394.3183124929;12918.935405322913;692.8475735338218;694.9756758513428;700.551646945955;713.169157931015;713.7715930902166;5384.434634363413;5389.326334208224;5395.190721391744;5448.101063324507;5520.420355862577;114322;116563;116766.41616791862;117457;117494;106414;109519;110108.4494577522;110870;111250.44374778074;91351.5432422847;91974;94002.529987351;94476;94869.5256523713;9960.452175130438;9992.850107248543;10156.542955567165;10460.110890574082;10579.89420105789;42453.5754642462;44183;44405.555944440144;44788.77605611951;45202.32196516989;57428.71285643545;58409;58439.415605843395;58591.4140858603;59524.70237648784;15332.923335383251;15388.38446462129;15624.765628515808;15650.295736691649;15667;16443.177841108136;16495.34018639289;16632.584185395463;16660.833391666412;16681.416150434678;15403.229838508254;15667.529974100795;15853.128077955824;16039.198040098181;16084.43704470338;14849.331780069706;15128.470503532324;15257.771133432785;15299.388024478896;15364;14332.283385830458;14415.78376324377;14428;14460.783088253895;14567.344469498687;11411.885881141083;11521.308721476738;11606.70983225426;11668.299902005907;11790.587329443772;11110.222284440011;11277.887221127683;11323.603673871376;11593.420328983686;11756.647300580997 04:14:31 INFO - PID 14718 | __end_tp_report 04:14:31 INFO - PID 14718 | __start_cc_report 04:14:31 INFO - PID 14718 | _x_x_mozilla_cycle_collect,297 04:14:31 INFO - PID 14718 | __end_cc_report 04:14:31 INFO - PID 14718 | __startTimestamp1506338071476__endTimestamp 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | ------- Summary: start ------- 04:14:31 INFO - PID 14718 | Number of tests: 6 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#0] dojo.html Cycles:165 Average:6206.79 Median:6450.90 stddev:752.67 (11.7%) stddev-sans-first:713.90 04:14:31 INFO - PID 14718 | Values: 3080.6 3118.5 3120.3 3127.8 3132.2 6154.1 6217.5 6278.6 6309.7 6312.3 6225.9 6238.4 6249.1 6268.7 6313.8 5724.3 5748.9 5782.0 5785.2 5831.0 5805.2 5811.7 5842.6 5878.2 5934.7 5805.0 5867.2 5877.5 5890.4 5965.9 5586.8 5701.6 5793.5 5805.4 5931.1 5175.0 5649.2 5687.3 5785.4 5807.6 6222.0 6310.7 6321.5 6333.1 6372.0 6535.3 6601.2 6678.3 6686.3 6788.6 6790.5 6824.9 6872.9 6883.0 6903.4 6240.1 6292.4 6315.4 6341.6 6348.8 6837.3 6841.5 6895.9 6971.0 6982.1 6469.1 6473.9 6556.5 6580.2 6588.8 6693.6 6694.5 6710.6 6787.9 6882.4 6770.5 6785.3 6840.7 6846.0 6853.7 6746.3 6768.8 6793.6 6850.6 6893.6 5181.5 5278.2 5287.4 5332.7 5383.2 4672.8 4677.2 4696.9 4716.7 4728.2 5768.0 5808.7 5835.3 5837.0 5917.1 6707.4 6778.6 6858.7 6877.6 6897.1 5814.7 5847.1 5897.0 5911.2 5916.1 5690.9 5791.1 5798.3 5881.4 5887.6 6489.3 6608.0 6611.6 6677.7 6678.2 6785.4 6811.2 6822.2 6870.6 6896.4 6412.7 6519.6 6542.0 6542.4 6572.1 6610.7 6655.7 6675.1 6676.9 6688.8 6654.2 6802.3 6805.5 6826.0 6856.6 6500.6 6515.1 6577.6 6613.1 6621.0 6400.6 6456.7 6495.2 6562.6 6598.2 6409.1 6473.9 6496.9 6511.1 6531.1 6509.6 6682.4 6692.7 6699.2 6703.2 6327.2 6338.1 6359.7 6431.7 6450.9 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#1] ext.html Cycles:180 Average:37773.24 Median:37529.62 stddev:52778.81 (140.6%) stddev-sans-first:52854.10 04:14:31 INFO - PID 14718 | Values: 862.7 873.8 878.7 889.7 911.3 40712.0 41884.8 42090.4 42174.2 42240.6 38558.8 38835.0 38960.6 38985.4 39171.8 7127.2 7139.5 7233.5 7255.3 7273.5 7029.5 7191.3 7220.7 7232.8 7243.2 7226.0 7805.6 8128.2 8132.5 8133.6 8015.0 8036.1 8087.4 8098.0 8111.5 7687.7 7938.2 7954.3 7982.1 7982.6 37577.8 37908.6 37953.1 38057.0 38121.2 3499.7 3508.3 3518.4 3539.0 3541.0 31708.0 31740.7 32094.2 32097.7 32099.0 5152.6 5175.6 5192.3 5215.3 5262.8 187179.0 187806.0 190071.0 191375.0 191616.0 48495.3 48522.0 48670.5 48998.0 49025.8 51802.2 51825.5 51973.0 52018.5 52047.7 16205.8 16427.3 16446.6 16515.7 16521.9 13224.3 13230.1 13344.7 13364.7 13376.8 14600.9 14779.1 14911.7 14964.8 15027.0 5598.3 5716.8 5795.4 5809.3 5840.8 984.4 991.9 993.5 999.0 1024.2 22678.5 22795.4 22972.3 22972.7 23492.2 1085.8 1097.5 1108.8 1114.0 1120.6 12296.6 12344.3 12345.8 12386.1 12488.4 219253.0 220040.0 221966.0 222061.0 222284.0 45458.0 53879.0 54236.7 54597.7 54712.7 1307.4 1434.8 1456.5 1457.9 1471.6 27787.7 27825.7 28118.3 28224.7 28520.6 39865.0 40069.8 40105.4 40138.8 40158.8 199202.0 199516.0 200072.0 200661.0 201638.0 36127.3 37159.0 37277.6 37552.0 37582.2 43772.3 43929.3 44514.6 44824.6 44839.3 32251.0 32314.2 32412.7 32542.7 32770.0 32810.2 32978.7 33071.3 33076.8 33148.5 36171.6 37507.2 37924.1 38029.2 38061.4 30875.4 31736.8 31935.0 32095.5 32339.4 23294.0 23344.4 23532.4 23559.9 23625.5 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#2] jquery.html Cycles:180 Average:34356.51 Median:22915.76 stddev:78164.89 (341.1%) stddev-sans-first:78343.01 04:14:31 INFO - PID 14718 | Values: 575.3 589.8 600.7 601.2 610.9 22127.4 22482.3 22828.1 22913.4 22918.1 23645.4 24760.0 24782.4 24946.6 25336.9 6381.1 6410.8 6438.1 6469.4 6490.4 6373.1 6389.3 6450.5 6485.0 6492.3 6200.8 6439.8 6557.8 6568.8 6571.4 6551.0 6567.9 6576.1 6588.2 6602.8 6133.7 6147.6 6158.3 6169.3 6176.2 28185.9 28988.9 29125.1 29181.6 29374.7 1995.9 2087.4 2088.5 2092.8 2100.0 18664.8 19195.7 19244.1 19471.2 19570.5 2470.7 2503.2 2518.4 2536.8 2542.6 124549.0 130337.3 131085.0 138763.0 143694.3 14638.6 14691.9 14985.8 15006.8 15394.7 23266.1 23500.9 23501.8 23677.5 23703.2 9871.0 9955.4 9988.9 10009.4 10073.1 9047.2 9224.3 9248.4 9276.0 9318.0 8916.4 8951.7 8998.5 9069.2 9287.6 2795.9 2815.8 2819.3 2825.3 2844.0 947.7 971.8 973.3 978.7 980.1 18409.3 18901.6 18959.2 19051.0 19102.1 1018.0 1023.3 1034.7 1040.2 1050.7 10469.3 10827.7 10848.6 10890.8 10903.4 449626.0 459564.0 461697.0 469040.0 471242.6 109533.5 110175.0 113235.0 113294.4 113500.0 1166.7 1215.6 1237.4 1264.8 1276.8 20079.9 21290.0 21324.7 21404.0 21553.4 57380.4 61857.0 61891.4 64040.4 64336.4 76789.6 77053.6 78720.6 79514.6 80668.0 19603.4 19632.9 19729.5 19979.6 19982.2 22090.8 22319.6 22389.3 22600.8 22638.4 17748.1 17864.3 18202.3 18243.5 18359.9 15532.6 16533.4 16548.3 16754.4 16798.7 18386.8 18439.1 18452.4 18598.7 18621.7 18377.9 19335.6 19428.2 19514.2 19529.4 17382.8 17629.5 17668.9 17823.7 17898.2 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#3] mootools.html Cycles:180 Average:9609.49 Median:2673.00 stddev:6915.29 (258.7%) stddev-sans-first:6906.37 04:14:31 INFO - PID 14718 | Values: 1279.7 1362.5 1385.5 1389.9 1399.9 15744.1 16152.1 16156.4 16275.1 16291.5 15830.5 15833.4 15901.7 15938.0 16013.0 2825.0 2857.2 2885.1 2889.5 2895.9 2641.8 2749.7 2757.0 2775.3 2777.4 2704.2 2783.9 2790.5 2794.1 2801.9 2830.5 2866.0 2883.6 2891.9 2898.6 2738.3 2759.0 2782.1 2783.7 2790.9 15017.5 15489.0 15496.4 15535.1 15582.9 8205.4 8400.5 8441.4 8446.7 8474.3 5556.8 5714.5 5742.2 5821.6 5876.6 2019.3 2024.6 2026.5 2041.6 2063.9 26116.7 27504.6 27790.2 27885.4 28090.2 6999.9 7097.0 7203.2 7220.5 7276.2 17315.0 17395.8 17409.5 17412.6 17526.0 7718.6 8135.4 8156.9 8213.5 8240.4 7466.3 7557.5 7615.2 7633.5 7705.9 5275.6 5420.6 5442.5 5449.6 5479.9 1450.1 1612.8 1620.3 1652.3 1662.5 775.3 900.2 912.6 915.9 927.0 7511.5 7592.3 7662.4 7668.7 7802.4 999.8 1005.4 1008.9 1014.6 1026.3 4565.2 4631.9 4664.1 4680.7 4770.5 22371.9 22455.3 22473.9 22698.5 22828.0 21788.0 22658.3 22675.5 22689.3 22744.7 11132.8 11877.1 11904.9 12055.9 12067.9 8423.2 8447.3 8529.3 8715.6 8747.8 18379.8 18626.1 18824.2 18964.4 19336.9 18884.2 19906.5 19954.3 20046.4 20344.0 11078.6 11259.5 11289.2 11290.8 11381.8 13995.0 14034.7 14293.0 14387.7 14492.5 11284.1 11591.4 11699.9 11721.0 11801.5 11093.0 11223.9 11350.6 11384.3 11482.8 10035.1 10165.1 10175.9 10206.0 10269.0 7455.8 7593.1 7599.5 7612.5 7622.8 7569.6 7586.5 7652.2 7784.9 7820.7 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#4] prototype.html Cycles:180 Average:5539.76 Median:6166.25 stddev:1534.32 (24.9%) stddev-sans-first:1491.38 04:14:31 INFO - PID 14718 | Values: 506.3 528.7 539.9 548.3 551.6 6304.7 6382.6 6418.8 6433.9 6457.0 6213.6 6217.3 6217.7 6259.2 6267.5 5162.3 5242.5 5249.9 5260.5 5292.8 5184.7 5235.4 5260.2 5288.2 5299.3 4852.9 4976.1 4981.9 5071.3 5197.3 4984.9 4997.4 5026.0 5040.5 5068.5 4493.9 4504.1 4533.2 4535.1 4545.8 5983.1 6098.6 6157.4 6161.8 6164.8 5716.7 5765.2 5767.4 5769.7 5820.4 6526.0 6559.6 6564.5 6582.3 6651.0 4943.9 4958.9 4970.3 4972.8 4999.8 6680.9 6778.9 6786.9 6911.5 6945.6 5007.2 5033.5 5055.0 5076.8 5092.8 5067.0 5085.8 5164.6 5167.8 5175.2 5739.0 5744.4 5773.5 5795.7 5817.2 6601.8 6644.9 6683.7 6694.4 6713.4 1766.4 1767.4 1785.7 1788.3 1789.9 1170.3 1219.1 1240.5 1255.7 1260.6 5615.0 5697.2 5706.8 5707.3 5805.3 6730.2 6749.2 6801.4 6815.2 6841.2 5675.7 5733.0 5769.6 5785.2 5836.0 3547.3 3556.8 3560.2 3560.6 3584.3 6094.8 6402.6 6461.5 6485.9 6512.4 6736.9 6782.6 6837.1 6840.2 6939.9 6390.5 6392.5 6492.3 6516.5 6555.9 6684.6 6708.6 6738.7 6803.2 6862.2 6656.2 6666.7 6778.3 6795.5 6815.2 6514.2 6565.0 6588.0 6597.5 6621.1 4910.4 4938.3 5023.3 5027.9 5042.8 6405.8 6491.6 6680.6 6727.9 6774.6 6449.5 6475.4 6494.7 6500.3 6522.2 6387.1 6411.0 6447.4 6499.0 6521.2 6277.0 6326.2 6344.4 6373.7 6401.3 6505.9 6554.3 6561.2 6588.8 6654.2 6167.7 6245.0 6262.4 6308.1 6394.0 04:14:31 INFO - PID 14718 | 04:14:31 INFO - PID 14718 | [#5] yui.html Cycles:180 Average:24241.41 Median:20471.19 stddev:35333.95 (172.6%) stddev-sans-first:35389.57 04:14:31 INFO - PID 14718 | Values: 888.7 903.5 919.3 920.9 935.8 19846.8 20637.8 20679.0 20685.3 20726.8 18807.0 18965.2 19408.0 19510.3 19643.3 956.0 957.9 958.9 960.1 963.0 981.3 985.6 986.8 989.2 993.2 992.3 1021.5 1031.2 1038.6 1040.8 1035.1 1035.2 1036.7 1041.5 1041.8 704.8 731.4 746.3 751.3 755.4 20838.0 20954.0 21046.2 21046.9 21101.7 19403.8 19417.0 20163.7 20239.0 20304.6 17301.4 17475.8 17726.6 17737.4 17942.5 3774.7 3817.5 3824.8 3826.8 3836.6 142299.0 143050.0 145402.3 147063.0 147227.0 16405.3 16628.3 16680.1 16820.4 16834.7 30649.5 30778.4 32078.5 32517.5 32838.3 8839.3 8849.7 8869.2 8924.7 8925.9 3946.8 3962.4 3966.3 4040.2 4081.4 5486.4 5489.8 5524.5 5577.6 5587.3 964.7 998.3 1009.3 1015.4 1019.6 688.6 700.9 713.9 715.4 719.3 11806.6 11806.9 12189.8 12394.3 12918.9 692.8 695.0 700.6 713.2 713.8 5384.4 5389.3 5395.2 5448.1 5520.4 114322.0 116563.0 116766.4 117457.0 117494.0 106414.0 109519.0 110108.4 110870.0 111250.4 91351.5 91974.0 94002.5 94476.0 94869.5 9960.5 9992.9 10156.5 10460.1 10579.9 42453.6 44183.0 44405.6 44788.8 45202.3 57428.7 58409.0 58439.4 58591.4 59524.7 15332.9 15388.4 15624.8 15650.3 15667.0 16443.2 16495.3 16632.6 16660.8 16681.4 15403.2 15667.5 15853.1 16039.2 16084.4 14849.3 15128.5 15257.8 15299.4 15364.0 14332.3 14415.8 14428.0 14460.8 14567.3 11411.9 11521.3 11606.7 11668.3 11790.6 11110.2 11277.9 11323.6 11593.4 11756.6 04:14:31 INFO - PID 14718 | -------- Summary: end -------- 04:14:31 INFO - PID 14718 | 04:14:32 INFO - TEST-INFO | 14718: exit 0 04:14:32 INFO - TEST-OK | dromaeo_css | took 1114035ms 04:14:32 INFO - TEST-START | kraken 04:14:32 INFO - Initialising browser for kraken test... 04:14:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56273/getInfo.html -profile /tmp/tmpttXwOB/profile 04:14:32 INFO - TEST-INFO | started process 15098 (/builds/slave/test/build/application/firefox/firefox http://localhost:56273/getInfo.html) 04:14:38 INFO - TEST-INFO | 15098: exit 0 04:14:38 INFO - Browser initialized. 04:14:38 INFO - Running cycle 1/1 for kraken test... 04:14:38 INFO - Using env: {'DISPLAY': ':0', 04:14:38 INFO - 'HOME': '/home/cltbld', 04:14:38 INFO - 'JSGC_DISABLE_POISONING': '1', 04:14:38 INFO - 'LANG': 'en_US.UTF-8', 04:14:38 INFO - 'LANGUAGE': 'en_US:en', 04:14:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:14:38 INFO - 'LOGNAME': 'cltbld', 04:14:38 INFO - 'MAIL': '/var/mail/cltbld', 04:14:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:38 INFO - 'MOZ_ACCELERATED': '1', 04:14:38 INFO - 'MOZ_CRASHREPORTER': '1', 04:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:14:38 INFO - 'MOZ_NO_REMOTE': '1', 04:14:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:38 INFO - 'MOZ_WEBRENDER': '1', 04:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:38 INFO - 'NO_EM_RESTART': '1', 04:14:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:38 INFO - 'PWD': '/builds/slave/test', 04:14:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:38 INFO - 'RUST_BACKTRACE': 'full', 04:14:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:14:38 INFO - 'SHELL': '/bin/bash', 04:14:38 INFO - 'SHLVL': '1', 04:14:38 INFO - 'TERM': 'linux', 04:14:38 INFO - 'TMOUT': '86400', 04:14:38 INFO - 'USER': 'cltbld', 04:14:38 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979', 04:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:38 INFO - '_': '/tools/buildbot/bin/python'} 04:14:38 INFO - TEST-INFO | started process 15346 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpttXwOB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:39 INFO - PID 15346 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 04:14:39 INFO - PID 15346 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 04:14:39 INFO - PID 15346 | 04:14:39 INFO - PID 15346 | (/builds/slave/test/build/application/firefox/firefox:15405): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:14:39 INFO - PID 15346 | 04:14:50 INFO - PID 15346 | RSS: Main: 196108288 04:14:50 INFO - PID 15346 | 04:14:52 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56273/tests/kraken/driver.html?testName=audio-beat-detection) 04:14:53 INFO - PID 15346 | RSS: Main: 192851968 04:14:53 INFO - PID 15346 | 04:14:56 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56273/tests/kraken/driver.html?testName=audio-dft) 04:14:57 INFO - PID 15346 | RSS: Main: 193613824 04:14:57 INFO - PID 15346 | 04:15:00 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56273/tests/kraken/driver.html?testName=audio-fft) 04:15:00 INFO - PID 15346 | RSS: Main: 190263296 04:15:00 INFO - PID 15346 | 04:15:03 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56273/tests/kraken/driver.html?testName=audio-oscillator) 04:15:03 INFO - PID 15346 | RSS: Main: 190402560 04:15:03 INFO - PID 15346 | 04:15:05 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:15:06 INFO - PID 15346 | RSS: Main: 190611456 04:15:06 INFO - PID 15346 | 04:15:09 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56273/tests/kraken/driver.html?testName=imaging-darkroom) 04:15:10 INFO - PID 15346 | RSS: Main: 192937984 04:15:10 INFO - PID 15346 | 04:15:14 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56273/tests/kraken/driver.html?testName=imaging-desaturate) 04:15:14 INFO - PID 15346 | RSS: Main: 192098304 04:15:14 INFO - PID 15346 | 04:15:17 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56273/tests/kraken/driver.html?testName=json-parse-financial) 04:15:18 INFO - PID 15346 | RSS: Main: 193605632 04:15:18 INFO - PID 15346 | 04:15:19 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:15:20 INFO - PID 15346 | RSS: Main: 194068480 04:15:20 INFO - PID 15346 | 04:15:22 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:15:22 INFO - PID 15346 | RSS: Main: 191045632 04:15:22 INFO - PID 15346 | 04:15:25 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:15:25 INFO - PID 15346 | RSS: Main: 191250432 04:15:25 INFO - PID 15346 | 04:15:28 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:15:29 INFO - PID 15346 | RSS: Main: 191389696 04:15:29 INFO - PID 15346 | 04:15:32 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:15:32 INFO - PID 15346 | RSS: Main: 190627840 04:15:32 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | Cycle 1(1): loaded http://localhost:56273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56273/tests/kraken/driver.html?testName=ai-astar) 04:15:34 INFO - PID 15346 | RSS: Main: 192536576 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | __start_tp_report 04:15:34 INFO - PID 15346 | _x_x_mozilla_page_load 04:15:34 INFO - PID 15346 | _x_x_mozilla_page_load_details 04:15:34 INFO - PID 15346 | |i|pagename|runs| 04:15:34 INFO - PID 15346 | |0;ai-astar;116;113;120;109;99;110;112;108;113;114 04:15:34 INFO - PID 15346 | |1;audio-beat-detection;117;118;124;121;124;123;125;120;143;122 04:15:34 INFO - PID 15346 | |2;audio-dft;165;171;166;169;167;180;171;166;166;162 04:15:34 INFO - PID 15346 | |3;audio-fft;77;81;76;77;79;75;79;86;76;78 04:15:34 INFO - PID 15346 | |4;audio-oscillator;82;91;81;79;84;89;82;82;90;80 04:15:34 INFO - PID 15346 | |5;imaging-gaussian-blur;115;114;111;113;117;115;115;120;123;115 04:15:34 INFO - PID 15346 | |6;imaging-darkroom;152;156;151;150;151;152;155;152;151;151 04:15:34 INFO - PID 15346 | |7;imaging-desaturate;101;94;102;102;96;101;96;102;96;102 04:15:34 INFO - PID 15346 | |8;json-parse-financial;56;54;54;54;57;55;59;55;55;53 04:15:34 INFO - PID 15346 | |9;json-stringify-tinderbox;44;39;45;39;39;48;38;47;39;39 04:15:34 INFO - PID 15346 | |10;stanford-crypto-aes;78;83;82;81;79;74;80;80;75;76 04:15:34 INFO - PID 15346 | |11;stanford-crypto-ccm;115;125;124;114;123;111;120;112;115;122 04:15:34 INFO - PID 15346 | |12;stanford-crypto-pbkdf2;177;173;163;169;165;178;172;178;172;173 04:15:34 INFO - PID 15346 | |13;stanford-crypto-sha256-iterative;67;66;66;67;67;68;69;70;65;66 04:15:34 INFO - PID 15346 | __end_tp_report 04:15:34 INFO - PID 15346 | __start_cc_report 04:15:34 INFO - PID 15346 | _x_x_mozilla_cycle_collect,745 04:15:34 INFO - PID 15346 | __end_cc_report 04:15:34 INFO - PID 15346 | __startTimestamp1506338134740__endTimestamp 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | ------- Summary: start ------- 04:15:34 INFO - PID 15346 | Number of tests: 14 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.50 stddev:5.58 (4.9%) stddev-sans-first:5.67 04:15:34 INFO - PID 15346 | Values: 116.0 113.0 120.0 109.0 99.0 110.0 112.0 108.0 113.0 114.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:123.50 stddev:7.27 (5.9%) stddev-sans-first:7.30 04:15:34 INFO - PID 15346 | Values: 117.0 118.0 124.0 121.0 124.0 123.0 125.0 120.0 143.0 122.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#2] audio-dft Cycles:10 Average:168.30 Median:168.00 stddev:4.95 (2.9%) stddev-sans-first:5.10 04:15:34 INFO - PID 15346 | Values: 165.0 171.0 166.0 169.0 167.0 180.0 171.0 166.0 166.0 162.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#3] audio-fft Cycles:10 Average:78.40 Median:78.50 stddev:3.20 (4.1%) stddev-sans-first:3.36 04:15:34 INFO - PID 15346 | Values: 77.0 81.0 76.0 77.0 79.0 75.0 79.0 86.0 76.0 78.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:83.00 stddev:4.37 (5.3%) stddev-sans-first:4.58 04:15:34 INFO - PID 15346 | Values: 82.0 91.0 81.0 79.0 84.0 89.0 82.0 82.0 90.0 80.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:115.00 stddev:3.46 (3.0%) stddev-sans-first:3.66 04:15:34 INFO - PID 15346 | Values: 115.0 114.0 111.0 113.0 117.0 115.0 115.0 120.0 123.0 115.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:152.00 stddev:1.91 (1.3%) stddev-sans-first:2.03 04:15:34 INFO - PID 15346 | Values: 152.0 156.0 151.0 150.0 151.0 152.0 155.0 152.0 151.0 151.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:98.00 stddev:3.26 (3.3%) stddev-sans-first:3.39 04:15:34 INFO - PID 15346 | Values: 101.0 94.0 102.0 102.0 96.0 101.0 96.0 102.0 96.0 102.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.75 (3.2%) stddev-sans-first:1.83 04:15:34 INFO - PID 15346 | Values: 56.0 54.0 54.0 54.0 57.0 55.0 59.0 55.0 55.0 53.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#9] json-stringify-tinderbox Cycles:10 Average:41.70 Median:41.50 stddev:3.86 (9.3%) stddev-sans-first:4.00 04:15:34 INFO - PID 15346 | Values: 44.0 39.0 45.0 39.0 39.0 48.0 38.0 47.0 39.0 39.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:80.00 stddev:3.01 (3.8%) stddev-sans-first:3.18 04:15:34 INFO - PID 15346 | Values: 78.0 83.0 82.0 81.0 79.0 74.0 80.0 80.0 75.0 76.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#11] stanford-crypto-ccm Cycles:10 Average:118.10 Median:121.00 stddev:5.26 (4.3%) stddev-sans-first:5.46 04:15:34 INFO - PID 15346 | Values: 115.0 125.0 124.0 114.0 123.0 111.0 120.0 112.0 115.0 122.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:173.00 stddev:5.14 (3.0%) stddev-sans-first:5.13 04:15:34 INFO - PID 15346 | Values: 177.0 173.0 163.0 169.0 165.0 178.0 172.0 178.0 172.0 173.0 04:15:34 INFO - PID 15346 | 04:15:34 INFO - PID 15346 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.52 (2.3%) stddev-sans-first:1.62 04:15:34 INFO - PID 15346 | Values: 67.0 66.0 66.0 67.0 67.0 68.0 69.0 70.0 65.0 66.0 04:15:34 INFO - PID 15346 | -------- Summary: end -------- 04:15:34 INFO - PID 15346 | 04:15:35 INFO - PID 15346 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:15:35 INFO - TEST-INFO | 15346: exit 0 04:15:35 INFO - TEST-OK | kraken | took 63166ms 04:15:35 INFO - SUITE-END | took 1177s 04:15:35 INFO - Completed test suite (00:19:37) 04:15:35 INFO - javascript benchmark 04:15:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8600.088562208713, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3080.5841211436455, 3118.501039833627, 3120.3291292372137, 3127.7966932149416, 3132.15431833405, 6154.138420621117, 6217.471514921232, 6278.591891527053, 6309.68451577421, 6312.274088479826, 6225.875482490347, 6238.407351301619, 6249.125122482856, 6268.65522396268, 6313.842153946153, 5724.255846739918, 5748.856278593027, 5782.0, 5785.190073389729, 5830.970845145789, 5805.158252053449, 5811.651300921958, 5842.64944103353, 5878.177055212273, 5934.703264836762, 5805.0, 5867.2372591563035, 5877.471027607515, 5890.440408161217, 5965.88068238635, 5586.776528938837, 5701.600887937846, 5793.536517078624, 5805.36141024487, 5931.139984702216, 5175.027664169174, 5649.179221501013, 5687.345955215121, 5785.421457854223, 5807.622504537234, 6221.96889015552, 6310.684465776692, 6321.525885558602, 6333.145025421555, 6372.044193370985, 6535.281119076898, 6601.174853143357, 6678.332166783282, 6686.3313668632745, 6788.626625535593, 6790.456763458961, 6824.897626535606, 6872.862542749167, 6882.965585172092, 6903.447724182103, 6240.095186198021, 6292.370762923717, 6315.3684631536935, 6341.587796793194, 6348.77779277723, 6837.316268373223, 6841.521093523405, 6895.931040689629, 6970.965145174242, 6982.057422248033, 6469.061986012096, 6473.870522589522, 6556.541042127005, 6580.21037475506, 6588.769393071316, 6693.598384096969, 6694.464442844658, 6710.630915299607, 6787.864242715118, 6882.4149947254045, 6770.492213084039, 6785.287544807823, 6840.692168852314, 6845.965770171118, 6853.657317134123, 6746.291639377893, 6768.76309329181, 6793.558418702769, 6850.588964662136, 6893.551919125241, 5181.5077567631015, 5278.2346559749385, 5287.41838397776, 5332.7067011313975, 5383.2194331821665, 4672.836450724209, 4677.228257337501, 4696.9060618787435, 4716.740579268173, 4728.196206644879, 5768.0, 5808.738606762706, 5835.328937172195, 5837.0, 5917.142014407887, 6707.3628005339415, 6778.559393639398, 6858.6570671466225, 6877.552959057645, 6897.137857767779, 5814.651120932757, 5847.06446968483, 5896.970515147397, 5911.172435858984, 5916.083007133901, 5690.886182276331, 5791.102379131242, 5798.275215598051, 5881.382454842265, 5887.61730487517, 6489.286178520359, 6608.008798680236, 6611.570247933869, 6677.699503522354, 6678.232003319583, 6785.355391237825, 6811.182658080963, 6822.181338239443, 6870.587764734132, 6896.413804826646, 6412.711427985659, 6519.608823470607, 6541.967290163519, 6542.411182993554, 6572.14562106923, 6610.735570577123, 6655.700493477708, 6675.0654908313845, 6676.866462670817, 6688.765893193715, 6654.234763002123, 6802.319768023158, 6805.4895882808, 6826.0, 6856.588604683735, 6500.609963402211, 6515.12045873801, 6577.6053436792035, 6613.14029176204, 6620.966895165493, 6400.55196136266, 6456.677166141768, 6495.1556297681, 6562.606243625398, 6598.241202261609, 6409.070684750778, 6473.935260647333, 6496.935030649633, 6511.055896894829, 6531.052997315268, 6509.576877502947, 6682.398584127452, 6692.732290708317, 6699.196096468455, 6703.229128650072, 6327.17746692927, 6338.112664227104, 6359.650219237987, 6431.742730290736, 6450.903236451363], "value": 6148.572536867138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [862.710991817741, 873.7509809704236, 878.6529320918238, 889.6574818694804, 911.2846415563783, 40712.0, 41884.790576047155, 42090.36864447032, 42174.15651686965, 42240.57759422401, 38558.80720596393, 38835.0, 38960.61039389605, 38985.41521877174, 39171.80414097926, 7127.2160062393095, 7139.5359301645485, 7233.529820561673, 7255.34701876831, 7273.454490913176, 7029.5430796998035, 7191.280871912793, 7220.747273845416, 7232.8191795205275, 7243.167035790872, 7226.0, 7805.60971951403, 8128.227818357246, 8132.512049277033, 8133.633986470623, 8015.038195416558, 8036.116027236999, 8087.353011759074, 8098.028236611615, 8111.5133092014075, 7687.730929417499, 7938.24586664266, 7954.323882469997, 7982.121966583672, 7982.560959147303, 37577.81211093954, 37908.62091379106, 37953.0511737206, 38057.048573785876, 38121.23757524834, 3499.737519686034, 3508.3158784037123, 3518.4194607889667, 3538.9646103538894, 3541.008517615072, 31708.0, 31740.682593174235, 32094.19764505906, 32097.679023209937, 32099.0, 5152.587792976553, 5175.585953123741, 5192.273081768518, 5215.269862219292, 5262.815801446931, 187179.0, 187806.0, 190071.0, 191375.0, 191616.0, 48495.27257091147, 48522.0, 48670.5132948666, 48998.0, 49025.754871225414, 51802.22296665553, 51825.48174518206, 51973.0, 52018.47981520136, 52047.73976130095, 16205.837941620668, 16427.342906283615, 16446.588835279214, 16515.6696866062, 16521.91739041297, 13224.338783060808, 13230.147779310904, 13344.666383340495, 13364.73270534584, 13376.799348009788, 14600.926995365167, 14779.113253204841, 14911.70176596462, 14964.775528367083, 15027.0, 5598.300212473441, 5716.828050249772, 5795.420457954255, 5809.2738407699035, 5840.787566838222, 984.3749219245831, 991.9454430006275, 993.4684943555163, 998.9850152247722, 1024.1704219582161, 22678.546429071324, 22795.430114246945, 22972.310830675106, 22972.65541016886, 23492.177773777836, 1085.8045551800594, 1097.5445190246119, 1108.8281316395971, 1113.9554417823197, 1120.6301920366259, 12296.631101066982, 12344.259344439362, 12345.753084938431, 12386.132970691964, 12488.438020289108, 219253.0, 220040.0, 221966.0, 222061.0, 222284.0, 45458.0, 53879.0, 54236.72881635645, 54597.72701136469, 54712.726436367564, 1307.4247331174254, 1434.7991281220638, 1456.4975083596141, 1457.9052361596462, 1471.565888063024, 27787.749551269837, 27825.721742783124, 28118.297042573282, 28224.71775282303, 28520.57219141673, 39865.0, 40069.79965100156, 40105.39841902432, 40138.79930600328, 40158.79920600378, 199202.0, 199516.0, 200071.99964000087, 200661.0, 201638.0, 36127.27745445129, 37159.0, 37277.6272237285, 37552.0, 37582.24835503329, 43772.34341484817, 43929.34105988477, 44514.55485445104, 44824.55175448204, 44839.32741008822, 32251.0, 32314.192145196557, 32412.675873241908, 32542.674573253964, 32770.0, 32810.1797455056, 32978.67021329852, 33071.33857322888, 33076.83461582677, 33148.50277245795, 36171.63828361788, 37507.2498550022, 37924.05189870275, 38029.2394152121, 38061.42907856329, 30875.382492350476, 31736.841315793274, 31935.041948741575, 32095.518567221978, 32339.35321293514, 23294.0, 23344.416389590395, 23532.398994299507, 23559.882200588887, 23625.52748945046], "value": 17374.64732047837, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.3096284458651, 589.7847285740706, 600.7116584039661, 601.179390132469, 610.9358517355678, 22127.44681382966, 22482.325530234077, 22828.08687652496, 22913.4271643209, 22918.083276668935, 23645.40886477839, 24760.0, 24782.380440488996, 24946.625800613005, 25336.859841307138, 6381.074744162093, 6410.839729006789, 6438.130852334945, 6469.4177524022825, 6490.383413575702, 6373.139626150457, 6389.265234498046, 6450.451711604519, 6485.0, 6492.253390860064, 6200.813975580717, 6439.774607888725, 6557.836054098661, 6568.835779105537, 6571.441427478646, 6550.967245163767, 6567.93432065678, 6576.145101136869, 6588.176477940257, 6602.834929126762, 6133.6626485543275, 6147.600405973598, 6158.291796443422, 6169.290531588835, 6176.197094377702, 28185.859070704515, 28988.855055724798, 29125.126246212647, 29181.56227656587, 29374.706252937413, 1995.8902260375673, 2087.4363921741196, 2088.5092003379195, 2092.8325733941247, 2099.958000839975, 18664.813351866444, 19195.712064319046, 19244.134013969266, 19471.221151153935, 19570.51073723154, 2470.740572239907, 2503.249025292405, 2518.42076322445, 2536.7970562354967, 2542.580474221751, 124549.0, 130337.34831325877, 131085.0, 138763.0, 143694.28152859272, 14638.59383407399, 14691.914697913344, 14985.751857748006, 15006.774898376532, 15394.692106158038, 23266.06935722586, 23500.882495587754, 23501.764982350298, 23677.526449471257, 23703.170389036302, 9870.976449827767, 9955.352902061342, 9988.850167247498, 10009.399436033862, 10073.1437827784, 9047.185753282105, 9224.262059035262, 9248.398854074598, 9276.0, 9318.0, 8916.420432671855, 8951.73144805657, 8998.505082220541, 9069.183773460289, 9287.58205880737, 2795.9021434249703, 2815.8310501369983, 2819.323362393052, 2825.3360460291815, 2843.957340639892, 947.7109481608038, 971.8493633486821, 973.3186769261404, 978.6770365779275, 980.0983095552109, 18409.263629454672, 18901.62196756057, 18959.24163033491, 19051.047447627563, 19102.14040368159, 1017.9949100254452, 1023.3297190340339, 1034.7051090439188, 1040.1834559870617, 1050.658535975811, 10469.319494233003, 10827.729306767236, 10848.566057357617, 10890.836637450286, 10903.375495845992, 449626.0, 459564.0, 461697.0, 469040.0, 471242.5751484951, 109533.45233273783, 110175.0, 113235.0, 113294.43352783183, 113500.0, 1166.7258194324327, 1215.617080619602, 1237.406045098358, 1264.8039553869348, 1276.789329760579, 20079.92806474185, 21290.041948112063, 21324.680129797754, 21404.0, 21553.35339939804, 57380.42619573918, 61857.0, 61891.381086188565, 64040.35959640344, 64336.35663643491, 76789.61605191938, 77053.61473192822, 78720.60639696765, 79514.6024269898, 80668.0, 19603.411897643095, 19632.90183549073, 19729.506762331057, 19979.60040799205, 19982.20071197136, 22090.77909220887, 22319.553608927406, 22389.32832015042, 22600.773992259867, 22638.434039148495, 17748.11259436997, 17864.285428582712, 18202.271909124018, 18243.45269641913, 18359.90820045891, 15532.611684707972, 16533.421330253383, 16548.338066477205, 16754.4135955241, 16798.664026719154, 18386.81613183851, 18439.078046097373, 18452.446426607225, 18598.721019184453, 18621.720674189626, 18377.90811045936, 19335.613287734446, 19428.222871085, 19514.21943122259, 19529.414117576496, 17382.82617173812, 17629.471115866545, 17668.91165544164, 17823.73264401009, 17898.194581244137], "value": 12201.800245330734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.7312564361484, 1362.5163067111175, 1385.501219560958, 1389.9235542045183, 1399.8600139985997, 15744.134072626, 16152.111633860133, 16156.434524791635, 16275.104869232187, 16291.51125466235, 15830.525084247462, 15833.366665333373, 15901.681966360666, 15938.043717376937, 16013.0, 2824.985875070632, 2857.171420288119, 2885.0911962731775, 2889.523228667267, 2895.884164633412, 2641.7622413982735, 2749.6975332713387, 2757.0074773081674, 2775.292300463389, 2777.4445110977786, 2704.2428120126297, 2783.902563410281, 2790.539560972437, 2794.147784925597, 2801.887924483018, 2830.5329620612574, 2866.0398766413277, 2883.5818806272973, 2891.942161156765, 2898.5797059426372, 2738.288045108286, 2758.972410275912, 2782.1375373634037, 2783.7216278372202, 2790.916272511828, 15017.549473515812, 15489.0, 15496.380144794195, 15535.145566993811, 15582.922085389613, 8205.425620206588, 8400.495970241805, 8441.409101362908, 8446.746597602081, 8474.279686226679, 5556.777728890839, 5714.51426628737, 5742.2247996520355, 5821.592488525805, 5876.55925805562, 2019.2730616977876, 2024.5647185854964, 2026.5237669147887, 2041.550858811074, 2063.8968051597512, 26116.73883261162, 27504.58743118855, 27790.16629501098, 27885.442291154064, 28090.157295281173, 6999.895001574981, 7096.97395646464, 7203.207647158808, 7220.4584656151, 7276.163241227327, 17315.0, 17395.826041739674, 17409.47771566855, 17412.564685882953, 17526.036068016125, 7718.614069296513, 8135.389845761473, 8156.918430815735, 8213.466124701876, 8240.423170378015, 7466.25337466249, 7557.5465472071855, 7615.162332143571, 7633.465657404039, 7705.8844117338285, 5275.630705850554, 5420.620556561003, 5442.482964118402, 5449.64577302474, 5479.863003424946, 1450.1371683848095, 1612.7661489084016, 1620.3194658243567, 1652.339064374236, 1662.5178698177392, 775.3293401207915, 900.221308568097, 912.5847739278612, 915.8809354783835, 926.9664324278516, 7511.511751736229, 7592.305770671996, 7662.4253181011645, 7668.693252269958, 7802.414818888606, 999.8052327468605, 1005.3766664667953, 1008.9243306752023, 1014.5992333028413, 1026.312370711625, 4565.178267911808, 4631.884202894955, 4664.113818374498, 4680.7191568506, 4770.499097594772, 22371.888140559193, 22455.32634020982, 22473.88763056174, 22698.546029079327, 22828.0, 21788.0, 22658.320250392513, 22675.546489070457, 22689.319320420414, 22744.65883011756, 11132.833007505056, 11877.109216808774, 11904.940475297568, 12055.87944120565, 12067.879321206676, 8423.157684231528, 8447.324214062737, 8529.317654587741, 8715.607797648994, 8747.781305467413, 18379.81620183781, 18626.068696565388, 18824.15291311867, 18964.43106706801, 19336.903315483334, 18884.150213240162, 19906.50233744168, 19954.30159944453, 20046.39860804178, 20344.0, 11078.5568577256, 11259.549618015188, 11289.209755317377, 11290.830637540279, 11381.772364552497, 13995.0, 14034.719305614035, 14293.0, 14387.712245755236, 14492.492762753252, 11284.09727221804, 11591.362475063952, 11699.941500292443, 11721.0, 11801.468933897824, 11093.0, 11223.887761122285, 11350.602728904449, 11384.316940983568, 11482.77034459323, 10035.14701250402, 10165.085142337226, 10175.949120254352, 10206.0, 10269.0, 7455.776326710207, 7593.088829340514, 7599.544027358376, 7612.505187162817, 7622.847543049219, 7569.583672898044, 7586.506877052974, 7652.158262591, 7784.922150778646, 7820.726274580363], "value": 6860.709521356182, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.26591442408494, 528.7144941731465, 539.8824433422815, 548.3228213156751, 551.5504863536219, 6304.653244071575, 6382.585131966419, 6418.775342863, 6433.871322573552, 6456.967715161423, 6213.56505044647, 6217.285012223596, 6217.658028808413, 6259.186305780254, 6267.529935254863, 5162.328897243353, 5242.501962313593, 5249.947500525003, 5260.526552610264, 5292.788288468457, 5184.688918664873, 5235.424103348629, 5260.237265596486, 5288.15389537674, 5299.33758280215, 4852.878678033042, 4976.118627470579, 4981.850544483671, 5071.340725705653, 5197.272381866542, 4984.875378115558, 4997.350344455216, 5025.994801039807, 5040.47075057121, 5068.518490743373, 4493.932591011137, 4504.144212599595, 4533.184026875194, 4535.142859998213, 4545.818167273338, 5983.132445795379, 6098.634081955052, 6157.3534778848025, 6161.815145545641, 6164.815055548341, 5716.742746576414, 5765.1928729977835, 5767.394423585547, 5769.711514424262, 5820.35976042635, 6525.984566590933, 6559.639219842907, 6564.507661925375, 6582.308857569934, 6651.0, 4943.937053533472, 4958.925616115761, 4970.329005584236, 4972.825951091694, 4999.8250061248045, 6680.933190668128, 6778.864422711567, 6786.898196527056, 6911.481638877054, 6945.583265004116, 5007.172382080515, 5033.458103657629, 5054.974725126424, 5076.822311219164, 5092.796288148433, 5067.023860694332, 5085.771140298695, 5164.561012313995, 5167.819126330561, 5175.223716442564, 5739.0, 5744.368119506851, 5773.507028247934, 5795.695968407074, 5817.185594016841, 6601.834954126185, 6644.867102658017, 6683.699233534503, 6694.364035416725, 6713.395794378529, 1766.3905952446385, 1767.3637490503409, 1785.69643160663, 1788.2936240185054, 1789.8634367176937, 1170.2919733561243, 1219.1222319929636, 1240.5472002718943, 1255.6735248835187, 1260.602910083321, 5615.0, 5697.202391665171, 5706.771729130788, 5707.315122185364, 5805.332386775575, 6730.226024007302, 6749.156355455568, 6801.35387138231, 6815.2162501312, 6841.247462779091, 5675.687837168913, 5733.0, 5769.567282453832, 5785.218995435563, 5836.0, 3547.255076433926, 3556.8399422026073, 3560.2167523144867, 3560.590532088843, 3584.2831433713363, 6094.75620975156, 6402.615843049339, 6461.483081353481, 6485.8702825944165, 6512.446442052384, 6736.865262694719, 6782.559133656297, 6837.1111755471475, 6840.247572766992, 6939.895901561481, 6390.45681117101, 6392.497763025593, 6492.285848556749, 6516.5112616553, 6555.868882622321, 6684.63234522106, 6708.6310252936555, 6738.663066846736, 6803.183617965876, 6862.176538815374, 6656.201255849329, 6666.699998499982, 6778.288279730755, 6795.52431329822, 6815.216250131299, 6514.176030711408, 6564.9671751640935, 6588.0, 6597.50518711089, 6621.106150669791, 4910.410750709938, 4938.320970869921, 5023.271625614192, 5027.924581131213, 5042.823501177441, 6405.839854003687, 6491.578047427091, 6680.5991640501725, 6727.8990815138795, 6774.593524388552, 6449.516286278454, 6475.449586785176, 6494.740210391531, 6500.284968653539, 6522.184726909137, 6387.125793393085, 6411.0, 6447.387498187664, 6499.0, 6521.184851893513, 6277.0, 6326.177596912372, 6344.350468878032, 6373.681315934277, 6401.295857455583, 6505.869882602226, 6554.344565543408, 6561.245456772342, 6588.769393071219, 6654.208969541922, 6167.6916154193, 6245.0, 6262.405071518198, 6308.148399965946, 6394.0], "value": 5091.776789374802, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [888.6711916590863, 903.5437104262348, 919.2783664823115, 920.8940971788246, 935.752025713186, 19846.801531984693, 20637.793622063793, 20679.0, 20685.276015339467, 20726.792732072692, 18807.0, 18965.24139034437, 19408.0, 19510.31713890014, 19643.31248406306, 956.0296299256244, 957.9042095790435, 958.8705524754173, 960.1358777100595, 963.0225321298888, 981.2591493422456, 985.635314933472, 986.7730422002944, 989.198749013298, 993.2103977337997, 992.2558081438921, 1021.5045702834119, 1031.1544533482547, 1038.5638032026568, 1040.7866387390604, 1035.1460045462447, 1035.1511760356511, 1036.6786296248188, 1041.5261056219479, 1041.8437234414823, 704.8061783009662, 731.4185222747928, 746.2537462537463, 751.2600088912417, 755.4334249313015, 20838.0, 20954.0, 21046.158153673834, 21046.894765526227, 21101.68347474789, 19403.80596194048, 19417.0, 20163.697544536844, 20239.0, 20304.593908121755, 17301.394451194275, 17475.82524174755, 17726.645467090588, 17737.37919172823, 17942.461726148238, 3774.7168962327937, 3817.48463957365, 3824.7896365699876, 3826.751261168043, 3836.635519625631, 142299.0, 143050.0, 145402.27298863544, 147063.0, 147227.0, 16405.261763220682, 16628.3348666052, 16680.082595457123, 16820.411285604947, 16834.747478787827, 30649.540256895718, 30778.384432311675, 32078.51882221722, 32517.51223731646, 32838.3432331352, 8839.337049721296, 8849.734507964642, 8869.246114080375, 8924.732258032269, 8925.866112008325, 3946.8421263149153, 3962.385830196324, 3966.2662407454714, 4040.1919616076316, 4081.3673880548563, 5486.423925487767, 5489.752961116759, 5524.475174858382, 5577.553795696334, 5587.3015873015875, 964.7298756348234, 998.2562990571926, 1009.3187098708343, 1015.3501758874327, 1019.6431249062917, 688.6315821035722, 700.8948657701386, 713.8822094354373, 715.4348065028673, 719.3238355945499, 11806.645800625995, 11806.88193118075, 12189.817152742717, 12394.3183124929, 12918.935405322913, 692.8475735338218, 694.9756758513428, 700.551646945955, 713.169157931015, 713.7715930902166, 5384.434634363413, 5389.326334208224, 5395.190721391744, 5448.101063324507, 5520.420355862577, 114322.0, 116563.0, 116766.41616791862, 117457.0, 117494.0, 106414.0, 109519.0, 110108.4494577522, 110870.0, 111250.44374778074, 91351.5432422847, 91974.0, 94002.529987351, 94476.0, 94869.5256523713, 9960.452175130438, 9992.850107248543, 10156.542955567165, 10460.110890574082, 10579.89420105789, 42453.5754642462, 44183.0, 44405.555944440144, 44788.77605611951, 45202.32196516989, 57428.71285643545, 58409.0, 58439.415605843395, 58591.4140858603, 59524.70237648784, 15332.923335383251, 15388.38446462129, 15624.765628515808, 15650.295736691649, 15667.0, 16443.177841108136, 16495.34018639289, 16632.584185395463, 16660.833391666412, 16681.416150434678, 15403.229838508254, 15667.529974100795, 15853.128077955824, 16039.198040098181, 16084.43704470338, 14849.331780069706, 15128.470503532324, 15257.771133432785, 15299.388024478896, 15364.0, 14332.283385830458, 14415.78376324377, 14428.0, 14460.783088253895, 14567.344469498687, 11411.885881141083, 11521.308721476738, 11606.70983225426, 11668.299902005907, 11790.587329443772, 11110.222284440011, 11277.887221127683, 11323.603673871376, 11593.420328983686, 11756.647300580997], "value": 8884.62789243215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 120.0, 109.0, 99.0, 110.0, 112.0, 108.0, 113.0, 114.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 124.0, 121.0, 124.0, 123.0, 125.0, 120.0, 143.0, 122.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 171.0, 166.0, 169.0, 167.0, 180.0, 171.0, 166.0, 166.0, 162.0], "value": 168.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 81.0, 76.0, 77.0, 79.0, 75.0, 79.0, 86.0, 76.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 81.0, 79.0, 84.0, 89.0, 82.0, 82.0, 90.0, 80.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 111.0, 113.0, 117.0, 115.0, 115.0, 120.0, 123.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 156.0, 151.0, 150.0, 151.0, 152.0, 155.0, 152.0, 151.0, 151.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 94.0, 102.0, 102.0, 96.0, 101.0, 96.0, 102.0, 96.0, 102.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 57.0, 55.0, 59.0, 55.0, 55.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 39.0, 45.0, 39.0, 39.0, 48.0, 38.0, 47.0, 39.0, 39.0], "value": 41.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 82.0, 81.0, 79.0, 74.0, 80.0, 80.0, 75.0, 76.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 125.0, 124.0, 114.0, 123.0, 111.0, 120.0, 112.0, 115.0, 122.0], "value": 118.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 173.0, 163.0, 169.0, 165.0, 178.0, 172.0, 178.0, 172.0, 173.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 68.0, 69.0, 70.0, 65.0, 66.0], "value": 67.1, "unit": "score"}]}]} 04:15:35 INFO - Return code: 0 04:15:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:15:36 INFO - # TBPL SUCCESS # 04:15:36 INFO - Running post-action listener: _package_coverage_data 04:15:36 INFO - Running post-action listener: _resource_record_post_action 04:15:36 INFO - [mozharness: 2017-09-25 11:15:36.002964Z] Finished run-tests step (success) 04:15:36 INFO - Running post-run listener: _resource_record_post_run 04:15:36 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 2842624; Write bytes: 343429120; Read time: 2812; Write time: 288748 04:15:36 INFO - TinderboxPrint: CPU usage
13.3% 04:15:36 INFO - TinderboxPrint: I/O read bytes / time
2,842,624 / 2,812 04:15:36 INFO - TinderboxPrint: I/O write bytes / time
343,429,120 / 288,748 04:15:36 INFO - TinderboxPrint: CPU idle
8,264.2 (86.7%) 04:15:36 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 04:15:36 INFO - TinderboxPrint: Swap in / out
0 / 0 04:15:36 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10559488; Read time: 0; Write time: 1812 04:15:36 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:15:36 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 2838528; Write bytes: 332869632; Read time: 2796; Write time: 286936 04:15:36 INFO - Running post-run listener: _upload_blobber_files 04:15:36 INFO - Blob upload gear active. 04:15:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:15:36 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:15:36 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:15:36 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:15:37 INFO - (blobuploader) - INFO - Open directory for files ... 04:15:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:15:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:15:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:38 INFO - (blobuploader) - INFO - Done attempting. 04:15:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:15:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:15:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:41 INFO - (blobuploader) - INFO - Done attempting. 04:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:15:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:42 INFO - (blobuploader) - INFO - Done attempting. 04:15:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:15:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:43 INFO - (blobuploader) - INFO - Done attempting. 04:15:43 INFO - (blobuploader) - INFO - Iteration through files over. 04:15:43 INFO - Return code: 0 04:15:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:15:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:15:43 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82acce3eab7b3915aee2d6cb93f1aba16b4e684c16da58fa7a31e7421a85985af6e4d3d522ed982a7f781b40dd8d8e8d5b8072695ba3eb4df487e8d1b568a030", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d31c80540d0a8830c0b635541b2cc30c1b5206e2b4aaa94757705fce46bb83d1ab8874cdc118b7a02a57c557095edea29a7941fde811c2c84c03c143cd15793", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06a7946ba18618d37970dc6e255d6a545806b4bfde2959d27382a23df23b1245effce6338cc7b7a52fa38ec8df6bd98419dfd197f89adfd1c1c094b68b2000aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82217bc23b28d7c8e7b8fc676776447387fe18a8681a43ed87209430ad9a47b9cd3d5393e864d4caa07b783e3d4717bd5b827b380a35dd5a87b5b8b549ebe64b"} 04:15:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:15:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:15:43 INFO - Contents: 04:15:43 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82acce3eab7b3915aee2d6cb93f1aba16b4e684c16da58fa7a31e7421a85985af6e4d3d522ed982a7f781b40dd8d8e8d5b8072695ba3eb4df487e8d1b568a030", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d31c80540d0a8830c0b635541b2cc30c1b5206e2b4aaa94757705fce46bb83d1ab8874cdc118b7a02a57c557095edea29a7941fde811c2c84c03c143cd15793", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06a7946ba18618d37970dc6e255d6a545806b4bfde2959d27382a23df23b1245effce6338cc7b7a52fa38ec8df6bd98419dfd197f89adfd1c1c094b68b2000aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82217bc23b28d7c8e7b8fc676776447387fe18a8681a43ed87209430ad9a47b9cd3d5393e864d4caa07b783e3d4717bd5b827b380a35dd5a87b5b8b549ebe64b"} 04:15:43 INFO - Running post-run listener: copy_logs_to_upload_dir 04:15:43 INFO - Copying logs to upload dir... 04:15:43 INFO - mkdir: /builds/slave/test/build/upload/logs 04:15:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.452588 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-25 04:15:43.438102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:43.446150) ========= 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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82acce3eab7b3915aee2d6cb93f1aba16b4e684c16da58fa7a31e7421a85985af6e4d3d522ed982a7f781b40dd8d8e8d5b8072695ba3eb4df487e8d1b568a030", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d31c80540d0a8830c0b635541b2cc30c1b5206e2b4aaa94757705fce46bb83d1ab8874cdc118b7a02a57c557095edea29a7941fde811c2c84c03c143cd15793", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06a7946ba18618d37970dc6e255d6a545806b4bfde2959d27382a23df23b1245effce6338cc7b7a52fa38ec8df6bd98419dfd197f89adfd1c1c094b68b2000aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82217bc23b28d7c8e7b8fc676776447387fe18a8681a43ed87209430ad9a47b9cd3d5393e864d4caa07b783e3d4717bd5b827b380a35dd5a87b5b8b549ebe64b"} build_url:https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/SaUzvixmSkCRJGqkRd3BZQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82acce3eab7b3915aee2d6cb93f1aba16b4e684c16da58fa7a31e7421a85985af6e4d3d522ed982a7f781b40dd8d8e8d5b8072695ba3eb4df487e8d1b568a030", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d31c80540d0a8830c0b635541b2cc30c1b5206e2b4aaa94757705fce46bb83d1ab8874cdc118b7a02a57c557095edea29a7941fde811c2c84c03c143cd15793", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06a7946ba18618d37970dc6e255d6a545806b4bfde2959d27382a23df23b1245effce6338cc7b7a52fa38ec8df6bd98419dfd197f89adfd1c1c094b68b2000aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82217bc23b28d7c8e7b8fc676776447387fe18a8681a43ed87209430ad9a47b9cd3d5393e864d4caa07b783e3d4717bd5b827b380a35dd5a87b5b8b549ebe64b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:43.474399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:43.474733) ========= 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=5bd038846534b2fdfa68eeae000001e2-1506336632.193101-376883979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:15:43.556549) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:15:43.559918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:15:43.560289) ========= ========= Total master_lag: 0.27 =========