builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1505957363.5 results: success (0) revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.501791) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.502284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.502555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.520190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.520538) ========= 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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:29:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 106M=0s 2017-09-20 18:29:23 (106 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.285963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.822568) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.825920) ========= 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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.045762 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:23.884179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 18:29:23.884511) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61340c7debf6dccec3b863d1bc00426feac42fe7 --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 61340c7debf6dccec3b863d1bc00426feac42fe7 --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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:29:23,896 truncating revision to first 12 chars 2017-09-20 18:29:23,896 Setting DEBUG logging. 2017-09-20 18:29:23,896 attempt 1/10 2017-09-20 18:29:23,896 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61340c7debf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:29:25,155 unpacking tar archive at: mozilla-central-61340c7debf6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.604487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 18:29:25.508324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:25.508644) ========= script_repo_revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:25.509037) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:25.509300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:29:25.524966) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-20 18:29:25.525374) ========= /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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:25 INFO - MultiFileLogger online at 20170920 18:29:25 in /builds/slave/test 18:29:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:29:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:25 INFO - {'append_to_log': False, 18:29:25 INFO - 'base_work_dir': '/builds/slave/test', 18:29:25 INFO - 'blob_upload_branch': 'Firefox', 18:29:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:25 INFO - 'branch': 'Firefox', 18:29:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:25 INFO - 'code_coverage': False, 18:29:25 INFO - 'config_files': ('talos/linux_config.py',), 18:29:25 INFO - 'default_actions': ('clobber', 18:29:25 INFO - 'read-buildbot-config', 18:29:25 INFO - 'download-and-extract', 18:29:25 INFO - 'populate-webroot', 18:29:25 INFO - 'create-virtualenv', 18:29:25 INFO - 'install', 18:29:25 INFO - 'setup-mitmproxy', 18:29:25 INFO - 'run-tests'), 18:29:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:25 INFO - 'disable_ccov_upload': False, 18:29:25 INFO - 'disable_stylo': False, 18:29:25 INFO - 'download_minidump_stackwalk': True, 18:29:25 INFO - 'download_symbols': 'ondemand', 18:29:25 INFO - 'enable_stylo': False, 18:29:25 INFO - 'enable_webrender': False, 18:29:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:29:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:25 INFO - 'gecko_profile': False, 18:29:25 INFO - 'gecko_profile_interval': 0, 18:29:25 INFO - 'installer_path': 'installer.exe', 18:29:25 INFO - 'log_level': 'info', 18:29:25 INFO - 'log_name': 'talos', 18:29:25 INFO - 'log_to_console': True, 18:29:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:25 INFO - 'opt_config_files': (), 18:29:25 INFO - 'pip_index': False, 18:29:25 INFO - 'suite': 'dromaeojs-e10s', 18:29:25 INFO - 'system_bits': '32', 18:29:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:25 INFO - 'title': 'talos-linux64-ix-035', 18:29:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:25 INFO - 'use_talos_json': True, 18:29:25 INFO - 'verify': 'False', 18:29:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:25 INFO - 'work_dir': 'build'} 18:29:25 INFO - [mozharness: 2017-09-21 01:29:25.767605Z] Running clobber step. 18:29:25 INFO - Running pre-action listener: _resource_record_pre_action 18:29:25 INFO - Running main action method: clobber 18:29:25 INFO - rmtree: /builds/slave/test/build 18:29:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:27 INFO - Running post-action listener: _resource_record_post_action 18:29:27 INFO - [mozharness: 2017-09-21 01:29:27.331251Z] Finished clobber step (success) 18:29:27 INFO - [mozharness: 2017-09-21 01:29:27.331384Z] Running read-buildbot-config step. 18:29:27 INFO - Running pre-action listener: _resource_record_pre_action 18:29:27 INFO - Running main action method: read_buildbot_config 18:29:27 INFO - Using buildbot properties: 18:29:27 INFO - { 18:29:27 INFO - "project": "", 18:29:27 INFO - "product": "firefox", 18:29:27 INFO - "who": "kwierso@gmail.com", 18:29:27 INFO - "installer_path": "public/build/target.tar.bz2", 18:29:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:29:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:29:27 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:29:27 INFO - "stage_platform": "linux64-qr", 18:29:27 INFO - "basedir": "/builds/slave/test", 18:29:27 INFO - "buildnumber": 85, 18:29:27 INFO - "platform": "ubuntu64_hw_qr", 18:29:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:29:27 INFO - "slavebuilddir": "test", 18:29:27 INFO - "taskId": "bX6zPXB6S1-CPsq3jLKeHA", 18:29:27 INFO - "branch": "mozilla-central", 18:29:27 INFO - "script_repo_revision": "production", 18:29:27 INFO - "revision": "61340c7debf6dccec3b863d1bc00426feac42fe7", 18:29:27 INFO - "slavename": "talos-linux64-ix-035", 18:29:27 INFO - "repo_path": "mozilla-central" 18:29:27 INFO - } 18:29:27 INFO - Finding installer, test and symbols from parent task. 18:29:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bX6zPXB6S1-CPsq3jLKeHA'}, attempt #1 18:29:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bX6zPXB6S1-CPsq3jLKeHA'}, attempt #1 18:29:28 INFO - Task dependencies: YpEoj-OJRvCSQ0IhvHvM7g 18:29:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g'}, attempt #1 18:29:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:29:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json 18:29:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip 18:29:28 INFO - Running post-action listener: _resource_record_post_action 18:29:28 INFO - [mozharness: 2017-09-21 01:29:28.668689Z] Finished read-buildbot-config step (success) 18:29:28 INFO - [mozharness: 2017-09-21 01:29:28.668908Z] Running download-and-extract step. 18:29:28 INFO - Running pre-action listener: _resource_record_pre_action 18:29:28 INFO - Running main action method: download_and_extract 18:29:28 INFO - mkdir: /builds/slave/test/build/tests 18:29: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')]} 18:29:28 INFO - trying https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json 18:29:28 INFO - Downloading https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:29:29 INFO - Downloaded 989 bytes. 18:29:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:29:29 INFO - Using the following test package requirements: 18:29:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:29:29 INFO - u'common': [u'target.common.tests.zip'], 18:29:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:29:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:29:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:29:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:29:29 INFO - u'mozbase': [u'target.common.tests.zip'], 18:29:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:29:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:29:29 INFO - u'web-platform': [u'target.common.tests.zip', 18:29:29 INFO - u'target.web-platform.tests.tar.gz'], 18:29:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:29:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:29:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip 18:29:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:29:29 INFO - Fetch https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.common.tests.zip into memory 18:29:30 INFO - Content-Length response header: 54733806 18:29:30 INFO - Bytes received: 54733806 18:29:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip 18:29:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:29:40 INFO - Fetch https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.talos.tests.zip into memory 18:29:41 INFO - Content-Length response header: 14512509 18:29:41 INFO - Bytes received: 14512509 18:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:41 INFO - trying https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:29:41 INFO - Downloading https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:29:43 INFO - Downloaded 61663650 bytes. 18:29:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:29:43 INFO - mkdir: /builds/slave/test/properties 18:29:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:43 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:43 INFO - Contents: 18:29:43 INFO - build_url:https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - Running post-action listener: find_tests_for_verification 18:29:43 INFO - Running post-action listener: set_extra_try_arguments 18:29:43 INFO - [mozharness: 2017-09-21 01:29:43.073645Z] Finished download-and-extract step (success) 18:29:43 INFO - [mozharness: 2017-09-21 01:29:43.073743Z] Running populate-webroot step. 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: populate_webroot 18:29:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['tresize', 18:29:43 INFO - 'tcanvasmark']}, 18:29:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['dromaeo_css', 18:29:43 INFO - 'kraken']}, 18:29:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'tests': ['damp', 'tps']}, 18:29:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['damp', 'tps']}, 18:29:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['dromaeo_dom']}, 18:29:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:29:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['basic_compositor_video', 18:29:43 INFO - 'glvideo']}, 18:29:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:29:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['ts_paint_webext', 18:29:43 INFO - 'tp5o_webext']}, 18:29:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_many_windows', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_many_windows', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:29:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:29:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['perf_reftest_singletons']}, 18:29:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['bloom_basic']}, 18:29:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:43 INFO - 'tsvgr_opacity', 18:29:43 INFO - 'tart', 18:29:43 INFO - 'tscrollx', 18:29:43 INFO - 'tsvg_static']}, 18:29:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['tsvgx', 18:29:43 INFO - 'tsvgr_opacity', 18:29:43 INFO - 'tart', 18:29:43 INFO - 'tscrollx', 18:29:43 INFO - 'tsvg_static']}, 18:29:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:29:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo'], 18:29:43 INFO - 'tests': ['tp5o']}, 18:29:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:43 INFO - 'talos_options': ['--mitmproxy', 18:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:43 INFO - '--firstNonBlankPaint'], 18:29:43 INFO - 'tests': ['tp6_google', 18:29:43 INFO - 'tp6_youtube', 18:29:43 INFO - 'tp6_amazon', 18:29:43 INFO - 'tp6_facebook']}, 18:29:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo', 18:29:43 INFO - '--mitmproxy', 18:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:43 INFO - '--firstNonBlankPaint'], 18:29:43 INFO - 'tests': ['tp6_google', 18:29:43 INFO - 'tp6_youtube', 18:29:43 INFO - 'tp6_amazon', 18:29:43 INFO - 'tp6_facebook']}, 18:29:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:43 INFO - 'talos_options': ['--stylo-threads=1', 18:29:43 INFO - '--mitmproxy', 18:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:43 INFO - '--firstNonBlankPaint'], 18:29:43 INFO - 'tests': ['tp6_google', 18:29:43 INFO - 'tp6_youtube', 18:29:43 INFO - 'tp6_amazon', 18:29:43 INFO - 'tp6_facebook']}, 18:29:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--xperf_path', 18:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:43 INFO - 'tests': ['tp5n']}, 18:29:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-stylo', 18:29:43 INFO - '--xperf_path', 18:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:43 INFO - 'tests': ['tp5n']}}} 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - [mozharness: 2017-09-21 01:29:43.088582Z] Finished populate-webroot step (success) 18:29:43 INFO - [mozharness: 2017-09-21 01:29:43.088670Z] Running create-virtualenv step. 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: create_virtualenv 18:29:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:29:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:29:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:29:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:44 INFO - Installing pip...............done. 18:29:44 INFO - Return code: 0 18:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:29:44 INFO - Reading from file tmpfile_stdout 18:29:44 INFO - Output received: 18:29:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:29:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:44 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:29:44 INFO - Using env: {'DISPLAY': ':0', 18:29:44 INFO - 'HOME': '/home/cltbld', 18:29:44 INFO - 'LANG': 'en_US.UTF-8', 18:29:44 INFO - 'LANGUAGE': 'en_US:en', 18:29:44 INFO - 'LOGNAME': 'cltbld', 18:29:44 INFO - 'MAIL': '/var/mail/cltbld', 18:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:44 INFO - 'MOZ_NO_REMOTE': '1', 18:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:44 INFO - 'NO_EM_RESTART': '1', 18:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:44 INFO - 'PWD': '/builds/slave/test', 18:29:44 INFO - 'SHELL': '/bin/bash', 18:29:44 INFO - 'SHLVL': '1', 18:29:44 INFO - 'TERM': 'linux', 18:29:44 INFO - 'TMOUT': '86400', 18:29:44 INFO - 'USER': 'cltbld', 18:29:44 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:44 INFO - '_': '/tools/buildbot/bin/python'} 18:29:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:29:46 INFO - Downloading/unpacking pip>=1.5 18:29:46 INFO - Running setup.py egg_info for package pip 18:29:46 INFO - warning: no files found matching 'pip/cacert.pem' 18:29:46 INFO - warning: no files found matching '*.html' under directory 'docs' 18:29:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:29:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:29:46 INFO - Installing collected packages: pip 18:29:46 INFO - Found existing installation: pip 1.1 18:29:46 INFO - Uninstalling pip: 18:29:46 INFO - Successfully uninstalled pip 18:29:46 INFO - Running setup.py install for pip 18:29:46 INFO - warning: no files found matching 'pip/cacert.pem' 18:29:46 INFO - warning: no files found matching '*.html' under directory 'docs' 18:29:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:29:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:29:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:29:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:29:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:29:46 INFO - Successfully installed pip 18:29:46 INFO - Cleaning up... 18:29:46 INFO - Return code: 0 18:29:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:46 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:29:46 INFO - Using env: {'DISPLAY': ':0', 18:29:46 INFO - 'HOME': '/home/cltbld', 18:29:46 INFO - 'LANG': 'en_US.UTF-8', 18:29:46 INFO - 'LANGUAGE': 'en_US:en', 18:29:46 INFO - 'LOGNAME': 'cltbld', 18:29:46 INFO - 'MAIL': '/var/mail/cltbld', 18:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:46 INFO - 'MOZ_NO_REMOTE': '1', 18:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:46 INFO - 'NO_EM_RESTART': '1', 18:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:46 INFO - 'PWD': '/builds/slave/test', 18:29:46 INFO - 'SHELL': '/bin/bash', 18:29:46 INFO - 'SHLVL': '1', 18:29:46 INFO - 'TERM': 'linux', 18:29:46 INFO - 'TMOUT': '86400', 18:29:46 INFO - 'USER': 'cltbld', 18:29:46 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:46 INFO - '_': '/tools/buildbot/bin/python'} 18:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:46 INFO - Downloading/unpacking psutil>=3.1.1 18:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:47 INFO - Installing collected packages: psutil 18:29:47 INFO - Running setup.py install for psutil 18:29:47 INFO - building 'psutil._psutil_linux' extension 18:29:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:29:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:29:49 INFO - building 'psutil._psutil_posix' extension 18:29:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:29:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:49 INFO - Successfully installed psutil 18:29:49 INFO - Cleaning up... 18:29:49 INFO - Return code: 0 18:29:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:29:49 INFO - Using env: {'DISPLAY': ':0', 18:29:49 INFO - 'HOME': '/home/cltbld', 18:29:49 INFO - 'LANG': 'en_US.UTF-8', 18:29:49 INFO - 'LANGUAGE': 'en_US:en', 18:29:49 INFO - 'LOGNAME': 'cltbld', 18:29:49 INFO - 'MAIL': '/var/mail/cltbld', 18:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:49 INFO - 'MOZ_NO_REMOTE': '1', 18:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:49 INFO - 'NO_EM_RESTART': '1', 18:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:49 INFO - 'PWD': '/builds/slave/test', 18:29:49 INFO - 'SHELL': '/bin/bash', 18:29:49 INFO - 'SHLVL': '1', 18:29:49 INFO - 'TERM': 'linux', 18:29:49 INFO - 'TMOUT': '86400', 18:29:49 INFO - 'USER': 'cltbld', 18:29:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:49 INFO - '_': '/tools/buildbot/bin/python'} 18:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:29:50 INFO - Installing collected packages: mozsystemmonitor 18:29:50 INFO - Running setup.py install for mozsystemmonitor 18:29:50 INFO - Successfully installed mozsystemmonitor 18:29:50 INFO - Cleaning up... 18:29:51 INFO - Return code: 0 18:29:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:29:51 INFO - Using env: {'DISPLAY': ':0', 18:29:51 INFO - 'HOME': '/home/cltbld', 18:29:51 INFO - 'LANG': 'en_US.UTF-8', 18:29:51 INFO - 'LANGUAGE': 'en_US:en', 18:29:51 INFO - 'LOGNAME': 'cltbld', 18:29:51 INFO - 'MAIL': '/var/mail/cltbld', 18:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:51 INFO - 'MOZ_NO_REMOTE': '1', 18:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:51 INFO - 'NO_EM_RESTART': '1', 18:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:51 INFO - 'PWD': '/builds/slave/test', 18:29:51 INFO - 'SHELL': '/bin/bash', 18:29:51 INFO - 'SHLVL': '1', 18:29:51 INFO - 'TERM': 'linux', 18:29:51 INFO - 'TMOUT': '86400', 18:29:51 INFO - 'USER': 'cltbld', 18:29:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:51 INFO - '_': '/tools/buildbot/bin/python'} 18:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:51 INFO - Downloading/unpacking jsonschema==2.5.1 18:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:29:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:52 INFO - Downloading functools32-3.2.3-2.tar.gz 18:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:29:52 INFO - warning: no files found matching '*.txt' 18:29:52 INFO - no previously-included directories found matching 'build' 18:29:52 INFO - no previously-included directories found matching 'dist' 18:29:52 INFO - no previously-included directories found matching '.git*' 18:29:52 INFO - Installing collected packages: jsonschema, functools32 18:29:52 INFO - Running setup.py install for functools32 18:29:52 INFO - warning: no files found matching '*.txt' 18:29:52 INFO - no previously-included directories found matching 'build' 18:29:52 INFO - no previously-included directories found matching 'dist' 18:29:52 INFO - no previously-included directories found matching '.git*' 18:29:52 INFO - Successfully installed jsonschema functools32 18:29:52 INFO - Cleaning up... 18:29:53 INFO - Return code: 0 18:29:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:29:53 INFO - Using env: {'DISPLAY': ':0', 18:29:53 INFO - 'HOME': '/home/cltbld', 18:29:53 INFO - 'LANG': 'en_US.UTF-8', 18:29:53 INFO - 'LANGUAGE': 'en_US:en', 18:29:53 INFO - 'LOGNAME': 'cltbld', 18:29:53 INFO - 'MAIL': '/var/mail/cltbld', 18:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:53 INFO - 'MOZ_NO_REMOTE': '1', 18:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:53 INFO - 'NO_EM_RESTART': '1', 18:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:53 INFO - 'PWD': '/builds/slave/test', 18:29:53 INFO - 'SHELL': '/bin/bash', 18:29:53 INFO - 'SHLVL': '1', 18:29:53 INFO - 'TERM': 'linux', 18:29:53 INFO - 'TMOUT': '86400', 18:29:53 INFO - 'USER': 'cltbld', 18:29:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:53 INFO - '_': '/tools/buildbot/bin/python'} 18:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:29:53 INFO - Cleaning up... 18:29:53 INFO - Return code: 0 18:29:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:29:53 INFO - Using env: {'DISPLAY': ':0', 18:29:53 INFO - 'HOME': '/home/cltbld', 18:29:53 INFO - 'LANG': 'en_US.UTF-8', 18:29:53 INFO - 'LANGUAGE': 'en_US:en', 18:29:53 INFO - 'LOGNAME': 'cltbld', 18:29:53 INFO - 'MAIL': '/var/mail/cltbld', 18:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:53 INFO - 'MOZ_NO_REMOTE': '1', 18:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:53 INFO - 'NO_EM_RESTART': '1', 18:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:53 INFO - 'PWD': '/builds/slave/test', 18:29:53 INFO - 'SHELL': '/bin/bash', 18:29:53 INFO - 'SHLVL': '1', 18:29:53 INFO - 'TERM': 'linux', 18:29:53 INFO - 'TMOUT': '86400', 18:29:53 INFO - 'USER': 'cltbld', 18:29:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:53 INFO - '_': '/tools/buildbot/bin/python'} 18:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:53 INFO - Downloading/unpacking blobuploader==1.2.4 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:54 INFO - Downloading blobuploader-1.2.4.tar.gz 18:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:29:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:29:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:55 INFO - Downloading docopt-0.6.1.tar.gz 18:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:29:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:29:55 INFO - Running setup.py install for blobuploader 18:29:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:29:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:29:55 INFO - Running setup.py install for requests 18:29:55 INFO - Running setup.py install for docopt 18:29:55 INFO - Successfully installed blobuploader requests docopt 18:29:55 INFO - Cleaning up... 18:29:55 INFO - Return code: 0 18:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:55 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:29:55 INFO - Using env: {'DISPLAY': ':0', 18:29:55 INFO - 'HOME': '/home/cltbld', 18:29:55 INFO - 'LANG': 'en_US.UTF-8', 18:29:55 INFO - 'LANGUAGE': 'en_US:en', 18:29:55 INFO - 'LOGNAME': 'cltbld', 18:29:55 INFO - 'MAIL': '/var/mail/cltbld', 18:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:55 INFO - 'MOZ_NO_REMOTE': '1', 18:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:55 INFO - 'NO_EM_RESTART': '1', 18:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:55 INFO - 'PWD': '/builds/slave/test', 18:29:55 INFO - 'SHELL': '/bin/bash', 18:29:55 INFO - 'SHLVL': '1', 18:29:55 INFO - 'TERM': 'linux', 18:29:55 INFO - 'TMOUT': '86400', 18:29:55 INFO - 'USER': 'cltbld', 18:29:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:55 INFO - '_': '/tools/buildbot/bin/python'} 18:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:29:55 INFO - Running setup.py (path:/tmp/pip-DfUbW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:29:56 INFO - Running setup.py (path:/tmp/pip-cHxAzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:29:56 INFO - Running setup.py (path:/tmp/pip-l1hcOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:29:56 INFO - Running setup.py (path:/tmp/pip-X_abCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:29:56 INFO - Running setup.py (path:/tmp/pip-c0t074-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:29:56 INFO - Running setup.py (path:/tmp/pip-r_4dwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:29:56 INFO - Running setup.py (path:/tmp/pip-BPjxdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:29:56 INFO - Running setup.py (path:/tmp/pip-ZGq3ZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:29:56 INFO - Running setup.py (path:/tmp/pip-LO815D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:29:56 INFO - Running setup.py (path:/tmp/pip-1nYzRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:29:56 INFO - Running setup.py (path:/tmp/pip-Qb6U4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:29:56 INFO - Running setup.py (path:/tmp/pip-ArPXFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:29:56 INFO - Running setup.py (path:/tmp/pip-HwKvPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:29:56 INFO - Running setup.py (path:/tmp/pip-6XlgrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:56 INFO - Running setup.py (path:/tmp/pip-qUeY9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:29:57 INFO - Running setup.py (path:/tmp/pip-qWzruO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:29:57 INFO - Running setup.py (path:/tmp/pip-f5iluU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:29:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:29:57 INFO - Running setup.py install for manifestparser 18:29:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Running setup.py install for mozcrash 18:29:57 INFO - Running setup.py install for mozdebug 18:29:57 INFO - Running setup.py install for mozdevice 18:29:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Running setup.py install for mozfile 18:29:57 INFO - Running setup.py install for mozhttpd 18:29:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Running setup.py install for mozinfo 18:29:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Running setup.py install for mozInstall 18:29:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:29:57 INFO - Running setup.py install for mozleak 18:29:58 INFO - Running setup.py install for mozlog 18:29:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Running setup.py install for moznetwork 18:29:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Running setup.py install for mozprocess 18:29:58 INFO - Running setup.py install for mozprofile 18:29:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Running setup.py install for mozrunner 18:29:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Running setup.py install for mozscreenshot 18:29:58 INFO - Running setup.py install for moztest 18:29:58 INFO - Running setup.py install for mozversion 18:29:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:29:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:29:58 INFO - Cleaning up... 18:29:58 INFO - Return code: 0 18:29:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29: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')]} 18:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29: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')]} 18:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:58 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:29:58 INFO - Using env: {'DISPLAY': ':0', 18:29:58 INFO - 'HOME': '/home/cltbld', 18:29:58 INFO - 'LANG': 'en_US.UTF-8', 18:29:58 INFO - 'LANGUAGE': 'en_US:en', 18:29:58 INFO - 'LOGNAME': 'cltbld', 18:29:58 INFO - 'MAIL': '/var/mail/cltbld', 18:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:58 INFO - 'MOZ_NO_REMOTE': '1', 18:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:58 INFO - 'NO_EM_RESTART': '1', 18:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:58 INFO - 'PWD': '/builds/slave/test', 18:29:58 INFO - 'SHELL': '/bin/bash', 18:29:58 INFO - 'SHLVL': '1', 18:29:58 INFO - 'TERM': 'linux', 18:29:58 INFO - 'TMOUT': '86400', 18:29:58 INFO - 'USER': 'cltbld', 18:29:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:58 INFO - '_': '/tools/buildbot/bin/python'} 18:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:29:59 INFO - Running setup.py (path:/tmp/pip-ypn1KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:29:59 INFO - Running setup.py (path:/tmp/pip-bt5AEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:29:59 INFO - Running setup.py (path:/tmp/pip-yqy0mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:29:59 INFO - Running setup.py (path:/tmp/pip-lLbBV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:29:59 INFO - Running setup.py (path:/tmp/pip-DBJNvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:29:59 INFO - Running setup.py (path:/tmp/pip-MNeiSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:29:59 INFO - Running setup.py (path:/tmp/pip-FygX39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:29:59 INFO - Running setup.py (path:/tmp/pip-y0rK1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:29:59 INFO - Running setup.py (path:/tmp/pip-VsxU4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:29:59 INFO - Running setup.py (path:/tmp/pip-Q0WU_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:29:59 INFO - Running setup.py (path:/tmp/pip-eE6GZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:29:59 INFO - Running setup.py (path:/tmp/pip-pw1yYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:29:59 INFO - Running setup.py (path:/tmp/pip-rbzorJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:00 INFO - Running setup.py (path:/tmp/pip-r5lZuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:00 INFO - Running setup.py (path:/tmp/pip-k_WQxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:00 INFO - Running setup.py (path:/tmp/pip-vMrpg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:00 INFO - Running setup.py (path:/tmp/pip-7whqUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:01 INFO - Downloading blessings-1.6.tar.gz 18:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:01 INFO - Installing collected packages: blessings 18:30:01 INFO - Running setup.py install for blessings 18:30:01 INFO - Successfully installed blessings 18:30:01 INFO - Cleaning up... 18:30:01 INFO - Return code: 0 18:30:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:01 INFO - Reading from file tmpfile_stdout 18:30:01 INFO - Current package versions: 18:30:01 INFO - argparse == 1.2.1 18:30:01 INFO - blessings == 1.6 18:30:01 INFO - blobuploader == 1.2.4 18:30:01 INFO - docopt == 0.6.1 18:30:01 INFO - functools32 == 3.2.3-2 18:30:01 INFO - jsonschema == 2.5.1 18:30:01 INFO - manifestparser == 1.1 18:30:01 INFO - mozInstall == 1.13 18:30:01 INFO - mozcrash == 1.0 18:30:01 INFO - mozdebug == 0.1 18:30:01 INFO - mozdevice == 0.51 18:30:01 INFO - mozfile == 1.2 18:30:01 INFO - mozhttpd == 0.7 18:30:01 INFO - mozinfo == 0.10 18:30:01 INFO - mozleak == 0.1 18:30:01 INFO - mozlog == 3.5 18:30:01 INFO - moznetwork == 0.27 18:30:01 INFO - mozprocess == 0.25 18:30:01 INFO - mozprofile == 0.28 18:30:01 INFO - mozrunner == 6.13 18:30:01 INFO - mozscreenshot == 0.1 18:30:01 INFO - mozsystemmonitor == 0.3 18:30:01 INFO - moztest == 0.8 18:30:01 INFO - mozversion == 1.4 18:30:01 INFO - psutil == 3.1.1 18:30:01 INFO - requests == 1.2.3 18:30:01 INFO - wsgiref == 0.1.2 18:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:01 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:01 INFO - Using env: {'DISPLAY': ':0', 18:30:01 INFO - 'HOME': '/home/cltbld', 18:30:01 INFO - 'LANG': 'en_US.UTF-8', 18:30:01 INFO - 'LANGUAGE': 'en_US:en', 18:30:01 INFO - 'LOGNAME': 'cltbld', 18:30:01 INFO - 'MAIL': '/var/mail/cltbld', 18:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:01 INFO - 'MOZ_NO_REMOTE': '1', 18:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:01 INFO - 'NO_EM_RESTART': '1', 18:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:01 INFO - 'PWD': '/builds/slave/test', 18:30:01 INFO - 'SHELL': '/bin/bash', 18:30:01 INFO - 'SHLVL': '1', 18:30:01 INFO - 'TERM': 'linux', 18:30:01 INFO - 'TMOUT': '86400', 18:30:01 INFO - 'USER': 'cltbld', 18:30:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:01 INFO - '_': '/tools/buildbot/bin/python'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:30:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:03 INFO - Installing collected packages: simplejson 18:30:03 INFO - Running setup.py install for simplejson 18:30:03 INFO - building 'simplejson._speedups' extension 18:30:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:30:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:30:04 INFO - Successfully installed simplejson 18:30:04 INFO - Cleaning up... 18:30:04 INFO - Return code: 0 18:30:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:04 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 0x169d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1866a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a09a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:30:04 INFO - Using env: {'DISPLAY': ':0', 18:30:04 INFO - 'HOME': '/home/cltbld', 18:30:04 INFO - 'LANG': 'en_US.UTF-8', 18:30:04 INFO - 'LANGUAGE': 'en_US:en', 18:30:04 INFO - 'LOGNAME': 'cltbld', 18:30:04 INFO - 'MAIL': '/var/mail/cltbld', 18:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:04 INFO - 'MOZ_NO_REMOTE': '1', 18:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:04 INFO - 'NO_EM_RESTART': '1', 18:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:04 INFO - 'PWD': '/builds/slave/test', 18:30:04 INFO - 'SHELL': '/bin/bash', 18:30:04 INFO - 'SHLVL': '1', 18:30:04 INFO - 'TERM': 'linux', 18:30:04 INFO - 'TMOUT': '86400', 18:30:04 INFO - 'USER': 'cltbld', 18:30:04 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:04 INFO - '_': '/tools/buildbot/bin/python'} 18:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:04 INFO - Cleaning up... 18:30:04 INFO - Return code: 0 18:30:04 INFO - Running post-action listener: _resource_record_post_action 18:30:04 INFO - Running post-action listener: _start_resource_monitoring 18:30:04 INFO - Starting resource monitoring. 18:30:04 INFO - [mozharness: 2017-09-21 01:30:04.650613Z] Finished create-virtualenv step (success) 18:30:04 INFO - [mozharness: 2017-09-21 01:30:04.651068Z] Running install step. 18:30:04 INFO - Running pre-action listener: _resource_record_pre_action 18:30:04 INFO - Running main action method: install 18:30:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:04 INFO - Reading from file tmpfile_stdout 18:30:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:05 INFO - Reading from file tmpfile_stdout 18:30:05 INFO - Output received: 18:30:05 INFO - Usage: mozinstall [options] installer 18:30:05 INFO - Options: 18:30:05 INFO - -h, --help show this help message and exit 18:30:05 INFO - -d DEST, --destination=DEST 18:30:05 INFO - Directory to install application into. [default: 18:30:05 INFO - "/builds/slave/test"] 18:30:05 INFO - --app=APP Application being installed. [default: firefox] 18:30:05 INFO - mkdir: /builds/slave/test/build/application 18:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:30:22 INFO - Reading from file tmpfile_stdout 18:30:22 INFO - Output received: 18:30:22 INFO - /builds/slave/test/build/application/firefox/firefox 18:30:22 INFO - Running post-action listener: _resource_record_post_action 18:30:22 INFO - [mozharness: 2017-09-21 01:30:22.784135Z] Finished install step (success) 18:30:22 INFO - [mozharness: 2017-09-21 01:30:22.784455Z] Running setup-mitmproxy step. 18:30:22 INFO - Running pre-action listener: _resource_record_pre_action 18:30:22 INFO - Running main action method: setup_mitmproxy 18:30:22 INFO - Skipping: mitmproxy is not required 18:30:22 INFO - Running post-action listener: _resource_record_post_action 18:30:22 INFO - [mozharness: 2017-09-21 01:30:22.785571Z] Finished setup-mitmproxy step (success) 18:30:22 INFO - [mozharness: 2017-09-21 01:30:22.785790Z] Running run-tests step. 18:30:22 INFO - Running pre-action listener: _resource_record_pre_action 18:30:22 INFO - Running pre-action listener: _set_gcov_prefix 18:30:22 INFO - Running main action method: run_tests 18:30:22 WARNING - Try message not found. 18:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:30:22 INFO - Python 2.7.3 18:30:22 INFO - Return code: 0 18:30:22 INFO - grabbing minidump binary from tooltool 18:30: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')]} 18:30:22 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 0x1a0d090>, '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 0x1a0dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a07f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:30:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:30:22 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:30:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:30:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:22 INFO - Return code: 0 18:30:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:30:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:30:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:30:22 INFO - ENV: RUST_BACKTRACE is now full 18:30:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:30:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:30:22 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/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:30:22 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/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:30:22 INFO - Using env: {'DISPLAY': ':0', 18:30:22 INFO - 'HOME': '/home/cltbld', 18:30:22 INFO - 'LANG': 'en_US.UTF-8', 18:30:22 INFO - 'LANGUAGE': 'en_US:en', 18:30:22 INFO - 'LOGNAME': 'cltbld', 18:30:22 INFO - 'MAIL': '/var/mail/cltbld', 18:30:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:22 INFO - 'MOZ_ACCELERATED': '1', 18:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:22 INFO - 'MOZ_NO_REMOTE': '1', 18:30:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:22 INFO - 'MOZ_WEBRENDER': '1', 18:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:22 INFO - 'NO_EM_RESTART': '1', 18:30:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:22 INFO - 'PWD': '/builds/slave/test', 18:30:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:22 INFO - 'RUST_BACKTRACE': 'full', 18:30:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:30:22 INFO - 'SHELL': '/bin/bash', 18:30:22 INFO - 'SHLVL': '1', 18:30:22 INFO - 'TERM': 'linux', 18:30:22 INFO - 'TMOUT': '86400', 18:30:22 INFO - 'USER': 'cltbld', 18:30:22 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:22 INFO - '_': '/tools/buildbot/bin/python'} 18:30:22 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/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:30:23 INFO - mozversion application_buildid: 20170921001837 18:30:23 INFO - mozversion application_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:30:23 INFO - mozversion application_display_name: Nightly 18:30:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:23 INFO - mozversion application_name: Firefox 18:30:23 INFO - mozversion application_remotingname: firefox 18:30:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:30:23 INFO - mozversion application_vendor: Mozilla 18:30:23 INFO - mozversion application_version: 57.0a1 18:30:23 INFO - mozversion platform_buildid: 20170921001837 18:30:23 INFO - mozversion platform_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:30:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:30:23 INFO - mozversion platform_version: 57.0a1 18:30:23 INFO - using testdate: 1505957423 18:30:23 INFO - actual date: 1505957423 18:30:23 INFO - starting webserver on 'localhost:44931' 18:30:23 INFO - SUITE-START | Running 2 tests 18:30:23 INFO - TEST-START | dromaeo_css 18:30:23 INFO - Initialising browser for dromaeo_css test... 18:30:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44931/getInfo.html -profile /tmp/tmpyZ83Qs/profile 18:30:23 INFO - TEST-INFO | started process 3577 (/builds/slave/test/build/application/firefox/firefox http://localhost:44931/getInfo.html) 18:30:31 INFO - TEST-INFO | 3577: exit 0 18:30:31 INFO - Browser initialized. 18:30:31 INFO - Running cycle 1/1 for dromaeo_css test... 18:30:31 INFO - Using env: {'DISPLAY': ':0', 18:30:31 INFO - 'HOME': '/home/cltbld', 18:30:31 INFO - 'JSGC_DISABLE_POISONING': '1', 18:30:31 INFO - 'LANG': 'en_US.UTF-8', 18:30:31 INFO - 'LANGUAGE': 'en_US:en', 18:30:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:30:31 INFO - 'LOGNAME': 'cltbld', 18:30:31 INFO - 'MAIL': '/var/mail/cltbld', 18:30:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:31 INFO - 'MOZ_ACCELERATED': '1', 18:30:31 INFO - 'MOZ_CRASHREPORTER': '1', 18:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:30:31 INFO - 'MOZ_NO_REMOTE': '1', 18:30:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:31 INFO - 'MOZ_WEBRENDER': '1', 18:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:31 INFO - 'NO_EM_RESTART': '1', 18:30:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:31 INFO - 'PWD': '/builds/slave/test', 18:30:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:31 INFO - 'RUST_BACKTRACE': 'full', 18:30:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:30:31 INFO - 'SHELL': '/bin/bash', 18:30:31 INFO - 'SHLVL': '1', 18:30:31 INFO - 'TERM': 'linux', 18:30:31 INFO - 'TMOUT': '86400', 18:30:31 INFO - 'USER': 'cltbld', 18:30:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:31 INFO - '_': '/tools/buildbot/bin/python'} 18:30:31 INFO - TEST-INFO | started process 3823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyZ83Qs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:32 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:30:32 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:30:33 INFO - PID 3823 | 18:30:33 INFO - PID 3823 | (/builds/slave/test/build/application/firefox/firefox:3882): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:30:33 INFO - PID 3823 | 18:30:43 INFO - PID 3823 | RSS: Main: 205275136 18:30:43 INFO - PID 3823 | 18:33:40 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44931/tests/dromaeo/cssquery-ext.html) 18:33:42 INFO - PID 3823 | RSS: Main: 209379328 18:33:42 INFO - PID 3823 | 18:36:44 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-ext.html (next: http://localhost:44931/tests/dromaeo/cssquery-jquery.html) 18:36:45 INFO - PID 3823 | RSS: Main: 210817024 18:36:45 INFO - PID 3823 | 18:39:47 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44931/tests/dromaeo/cssquery-mootools.html) 18:39:48 INFO - PID 3823 | RSS: Main: 212217856 18:39:48 INFO - PID 3823 | 18:42:50 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44931/tests/dromaeo/cssquery-prototype.html) 18:42:50 INFO - PID 3823 | RSS: Main: 208240640 18:42:50 INFO - PID 3823 | 18:45:54 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44931/tests/dromaeo/cssquery-yui.html) 18:45:55 INFO - PID 3823 | RSS: Main: 209387520 18:45:55 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:44931/tests/dromaeo/cssquery-yui.html (next: http://localhost:44931/tests/dromaeo/cssquery-dojo.html) 18:48:57 INFO - PID 3823 | RSS: Main: 212172800 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | __start_tp_report 18:48:57 INFO - PID 3823 | _x_x_mozilla_page_load 18:48:57 INFO - PID 3823 | _x_x_mozilla_page_load_details 18:48:57 INFO - PID 3823 | |i|pagename|runs| 18:48:57 INFO - PID 3823 | |0;dojo.html;3076.8307743074124;3084.0747775667296;3084.6298444186696;3112.782105252632;3128.202308411356;6100.481459075984;6107.450329470346;6153.600015998963;6158.168647232622;6187.133801267826;6203.534734894878;6212.409821067003;6277.905831412532;6280.748770049193;6304.779332723356;5720.084786434171;5785.884282314351;5797.797077102302;5802.82591522255;5840.269966254218;5725.14122881569;5791.594588378815;5813.505852002585;5850.122481627769;5933.584649074566;5829.737661805208;5858.853528661775;5872;5886.88226235475;5888.32284287306;5554.833354999357;5672.06410942194;5676;5784.1902133701315;5810.418958104176;5700;5758.913616295759;5766.913496297559;5798.246227990377;5822.767089316422;6155.261368635747;6167.44492995628;6273.87452250957;6325.051242313643;6330.303666596671;6694.397504224595;6697.732090716366;6698.5980841149685;6745.224299205605;6809.557378770364;6776.728930842761;6784.864302713968;6850;6866.382025617669;6873.072135261726;6211.099390588384;6239.438450539428;6241.126242326078;6277.027060805583;6334.049892516159;6741.393274605309;6928.189495777302;6942.409895158867;6989.091418115612;7017.298270172942;6483.189601299838;6552.6068435894;6654.600723956578;6670.166229221348;6681.231658359254;6678.265390807007;6733.293004234485;6742.764003259862;6762.323767623297;6835.974603809468;6648.235452922879;6666.899996500057;6688.230853451818;6774.661266936732;6777.390034896883;6382.0745991831855;6474.708638111297;6593.5384523083885;6690;6816.07982922309;5294.470552944675;5313.2030195470215;5330.680159190461;5342.519173274346;5344.625876188629;4550.13547425988;4597.149527337453;4634.490206077328;4691.62467002639;4700.036492519025;5647.350554686182;5738.942610573924;5775.595708300462;5810.099434587646;5843.678597677169;6743.460523158234;6756.324367563303;6796.592204467748;6831.760888368884;6836.863262734718;5588.133839254922;5647.463490968352;5700.287464066992;5709.771609135588;5794.0439827428;5711.200431939532;5731.283589551306;5731.971340143356;5732.71336433185;5753;6391.1371964785085;6430.260520040161;6454.2577603575255;6534.379233972765;6547.476201903932;6701.32986701326;6763.966180169067;6796.320367963362;6806.421454176252;6831.077804496329;6403.263624683034;6461.709223085067;6471.514636402195;6473;6532.477401807749;6476.6114033158165;6573.441257493167;6573.506986976092;6612.735490580515;6671.232808227116;6606;6700.4639628830555;6719.361660642231;6748.831279217862;6753.493487988322;6421.8073457796345;6447.580907241015;6474.255460622088;6474.5791523550815;6475.449586785176;6565.540412171103;6617.900731489128;6619.801405957829;6621.768238111643;6644.933550664431;6475.967620161869;6479.481641468766;6488.091667166506;6495.707693153725;6566.212054553484;6545.574537655038;6582.275949645439;6604.3725846044545;6680.899786503109;6685.963675630091;6327.525435592441;6388.073729309317;6391.520635952415;6392.9360706392345;6427 18:48:57 INFO - PID 3823 | |1;ext.html;880.5773228850152;901.9357158552907;912.4388501071843;912.7991841794807;914.2320450821309;39168.41247381292;40211;43056.13887722244;43179.78410107949;43317;39529.604703953024;39557.40663890044;39563.60436395635;39847;40097;7305.525140865853;7348.853022939538;7377.487615038931;7387.704491820321;7833.725819596315;7903.209679032108;7998.64006119726;8000.67997280111;8007.639656215484;8013.599320034004;7997.760067197964;8031.076426210973;8044.195580441939;8049.074356448996;8052.154523775007;7951.562664053504;8001.599920004006;8012.759617211493;8015.118336982927;8027.879581806277;7811.375089992787;7845.137034926153;7847.1368149503505;7857.0964339101165;7884.053913530356;38507;38558.80720596379;38726;38838.41742373867;39051.41422878659;3464.185916309665;3484.26830365621;3498.930021399558;3501.8949431517094;3515.6308587598446;32695.346093078006;32951.17622059444;32956;33005;33096.50355244673;5133.897322053538;5135.820246291362;5163.8123231656555;5207.140821764404;5211.140161873286;185987;186202;186696;186733;187476;42614.78692606517;43040;49584.256236156485;49592.75203623959;49688;51529.48470515247;53123.73438132785;53221;53358.733206333716;53450;16352.75470867938;16370.263338149813;16388.918055409646;16406.425775097814;16451.42420015294;13524.32378381077;13668;13685.04204705681;13715.382807773674;13724.176549407068;14367.640808979859;14381.137131772128;14518.346674399678;14627.707445851023;14646.633834154018;5438.8912221755345;5455.944670984261;5474.698891561003;5570.080936645408;5661;960.9519524023771;975.3513913247752;975.8438649815995;976.5215044628081;981.1316984468797;19717.60564788696;20135;20212.98935053271;20356;20718;1068.8663917010374;1074.8441475985937;1076.2896488317672;1087.0868470484832;1092.6503518873883;11263.94368028171;11364.204505684524;11504.022158116486;11593.478293476814;11750.29498230109;214952;216268;216622;216873;217459;52711.20933186005;52775;52986.47013529815;53234.73382633062;53261;1427.1722401007391;1479.4304192886032;1487.8363380028188;1491.1202390589604;1497.5432493089713;21305.14779408806;21610.518076073546;21649;21706.782932170478;22167.778322217215;37956.24087518179;38125.61874381221;38188.618113818506;38307.61692383152;38313.23373532458;189338;189947;190119;190242;191097;30351;34044.31911361808;34813.47779783356;35755;36330.63669363378;44118.33822492729;44591.55408445874;44617.55382446134;44941.550584493736;45169;32326.19184520406;32445.67554324521;32564.185895351857;32635.673643263264;32830.17924551905;32321.51517727189;32522.674773251965;32710.509342359408;32770.67229327772;32962;36261.63738362688;36283.27433451264;36505.45241821428;36706.63293367032;37413.064673383386;31874;31993.36013279768;32201.838990805834;32250.35499290048;32349.35301294008;22582.661260080782;22611.886940565193;22682.092716291165;22882.885585571967;23138.652920206547 18:48:57 INFO - PID 3823 | |2;jquery.html;573.945475179858;584.2113147251214;596.4006173795337;600.8527910661888;628.318274671981;22036.669449958245;22788.544229115407;22897;23010;23027.76972230277;23507.647385289234;24320.811126998567;24364.878175609098;24448.14431494898;24507.632385514185;6630.436412904897;6681.298463661318;6684.231313398948;6723.798286051427;6785.966070169642;6639.502037347218;6741.730330786787;6745.898811517831;6747.662616869162;6748.966255168717;6267.843303917416;6485.6108633482145;6495.220573531183;6522.836929076763;6525.575837570566;6418.775342862978;6454.257760357572;6495.317991610884;6509.088727578142;6513.902291465632;5918.940810591882;5990.371011043821;6024.849378765566;6033.668148251844;6128.080787881809;27534.173974780788;28034.71965280362;28042.298942526395;28067.157985260474;28091.57862632062;2055.7122002919605;2073.9481512962147;2086.9165233390645;2090.560982193741;2099.6955441460905;18424.17091230898;18911.33810316646;19244.71132933007;19264.133114009903;19283.903580482147;2415.3961509622595;2452.754724527551;2463.0763463701114;2471.2462698876834;2485.2171565956664;118189.40905295504;123823;136634;138105;141126.29436852853;14804.297505786757;14927.581879721409;14955.486068234883;15002;15086.849131508545;22883.199088032172;23145.189918353113;23147.189848355563;23225;23345.883270583538;9973.202143828476;10142.898571014342;10192.847107293397;10201.540930658139;10526.894676059057;9438.103380179009;9529.428234305964;9575.616975320909;9681.51592420376;9742.659006934866;8769.780755481037;8994.685186018458;9016.729498115066;9061.501617411106;9065.365424420359;2774.542200536929;2783.8051336406256;2845.7438830505353;2873.813202141854;2896.189067061226;945.5697657565792;958.2334132693817;971.6016433262329;972.0036962113749;975.6487664440796;18305.81694183068;18617.162227699788;18698.910762466057;18877.81122188788;18916.243350265835;1027.7173777211326;1028.280203857303;1036.0623635609786;1036.160709825044;1042.4579218806177;10501.474926253657;10634.308769930085;10734.409607471664;10763;10973.396463194442;486275;498453;505075;507411;513780;113854;114558;114988;116453.835461646;116517.41741291409;1139.789139009286;1176.8705442401329;1249.9750004999848;1259.1123258103198;1272.4910035985495;20122.59754804925;20144;20297.594048119252;20772.272970445963;20784.1686332545;56330.43669563252;57822.710886446985;58947.41052589591;59052.40947590469;59917;74753;75066.62466687632;75963.24036759562;76480;76565.23434765582;14473;14511.854881451472;14529.27353632293;14545.636359091108;14570.198639074953;22272.88863555672;22436.551268974854;22514.887425562767;22585.887070564542;22907.54184916259;17678.46964591064;17782.822171778116;18173.54566135857;18175.272989080288;18183.636327273114;16000.119993400474;16133.596660083122;16238.918805405898;16370.590735231715;16421.753673694664;17679.73480397769;18549.629007420044;18701.719474207624;18820.52948676294;18831.717524236872;14330.49843255481;14499.275036248357;14509.201993890438;14521;14523.927380363031;15771.842281577037;16662.83337166613;16846.410375636795;16849.663006739553;17008.659826803643 18:48:57 INFO - PID 3823 | |3;mootools.html;1276.253391765817;1323.8146659467677;1329.7633201122958;1354.4311389216527;1361.0948719101798;15204.163770992605;15830.287637056332;15892.364305427769;15951.760723589156;15972.281247343868;15254.771178432306;15289.388424463008;15352.385904563833;15423.845761542381;15455.149966751824;2797.678266999291;2825.717428257168;2864.4127953769425;2888.407876385336;2895.565665150223;2713.81003329767;2738.780897528203;2763.9309017274527;2769.556870900656;2773.209635253953;2681.1554360376417;2746.052611848909;2761;2875.741183293503;2885.697001814811;2825.4631619992238;2859.1565488181036;2859.2137162280333;2865.054532004444;2866.498362786504;2682.973170268292;2747.162115554775;2747.32690490829;2759.448110377912;2768.8754006069576;14518.201498917555;14852;14889.404423823034;14969.925150374287;14996.925015374962;8217.753467395987;8672.392932494728;8726.563671816384;8736.039035706068;8743.562821858945;5743;5878.294604647427;5902.822915312548;5975.342712301644;5996.5202783777195;1994.6908229224493;2007.9196832126843;2016.6773316269328;2019.3538067818304;2068.338131797825;27208.319792005357;27512.862435687694;27536.17391478259;27562.448751024865;27601;6961.338672826175;7675.53946763196;7867.449278550561;7907.644156012993;8037.115917249098;17002.149892505324;17010.48968530946;17115.400960966308;17172.055536945587;17206;8028.598570071473;8092.757217283491;8544.401891867634;8863.335249856158;8872.911270887338;7676;7745.690172393041;7770.184130666312;7878.960605196938;7883.408744344197;5296.311479507716;5432.402435732066;5462.481064298885;5466.781328746885;5470.890582188334;1674.7655328254054;1677.5302915183768;1698.3291599818003;1700.753390758358;1709.640975395181;852.3564708644933;856.4304737349593;867.5011868175824;870.4472659861034;879.9956000220086;7260.297896759;7387.335139837333;7488.625568721543;7549.773506794805;7554.508956917783;714.0717067811823;720.4704542161508;726.7565365602477;734.7612026091434;739.0281779459993;5096.261042148942;5196.142636465017;5242.108841496878;5327.786888524415;5395.244665746799;21223.15107395687;21384.144634214455;21707.02318395676;21727.565448691254;21764.673529897063;20648.277310294066;21390.358289251348;21419.46451338729;21696.674549881765;22023.66964495534;11215.71960700989;11379.374134422686;11380.146489013357;11543.480543375568;11608.709782255512;7968;8209.876851847346;8231.19610348121;8247.21651443112;8279.58602069882;17971.460856174337;18452.723209152144;18789.718154227972;18968.905155474687;19143.329983450512;18384.264629415207;19196.328128515433;19271.90364048171;19455.221791128195;19634.410967670992;11217.326960382403;11314.660560183207;11324;11327.207095503236;11353.432328383382;13674.453021879013;13997.790033149307;14071.859281407054;14080.084794488326;14091.154530728189;11320.773584528428;11521.711957201138;11611.941940290244;11632.069434445393;12009.519619215134;11059.281146725438;11107.38909359993;11217.439128043728;11346.773064538827;11465.598704045651;10193.541290642088;10320.225983051452;10365.118964888068;10381.11760500366;10415.479226038819;7232.63836808147;7243.384312333511;7331.450141239535;7392.667329970057;7474.962625186839;7075.752348667772;7146.606936618536;7168.247334029852;7232.5660460370445;7307.050083489113 18:48:57 INFO - PID 3823 | |4;prototype.html;497.87801988512797;507.3708601334345;530.2651820560636;535.0369335196646;536.1261144334734;6269.686515674215;6275.68621568922;6288.465480434163;6313.305536390993;6370.458511026557;6161.630302181861;6163.845903852406;6254.655993920333;6284.340144284842;6286.685665716719;5120.411152717438;5145.253938178962;5147.794088236466;5160.225966105096;5161.896762064756;5043.84868453947;5060.924086138711;5064.620153488485;5093.923591146136;5102.36220472441;4727.834525791598;4812.181929072066;4875.512448755114;4915.557599816016;4954.430240522332;4875.219964805614;4888.584470320027;4889.533140057986;4931.556159945586;4965.981973695401;4318.762468064287;4382.737035777864;4387.210302145612;4397.890052748675;4402.8679139625865;5857.297124345063;5923.200367950358;5983.970080149571;6073.696315184223;6089.817305480843;5542.7782888684405;5546.944530554683;5593.244911936918;5595.048841696921;5640;6377.617342959437;6409.276057675516;6435.487660399803;6481.384268494486;6484.513661475408;4566.40636717228;4774.3077253798;4798.904021919577;4812.411904071648;4838.395200599925;6544.738210471575;6566.212054553484;6644.534882558223;6665.366790154928;6775.491838112211;5230.032443997872;5295.7087360195555;5336;5340.679559226382;5395.433479484676;5416.485433883775;5451.890962180734;5463.371712253142;5467;5469.453054694578;5756.942430575725;5817.272840894888;5834.504067154255;5856.1508581255475;5860.860785813147;6471;6515.413612774873;6540.215174179034;6545.312742162099;6585.473162147016;1878.164216923456;1880.4546681462223;1903.181631898297;1905.7236700678325;1911.1113332300547;1375.21612680771;1428.271581493425;1436.1885534672876;1436.8491308412674;1449.8187726534184;5512.751926163332;5543.445655434505;5569.916451253234;5587.161925711176;5670.121131224667;6430.839229019312;6451.903221451682;6464.450521705614;6472;6498.870022599616;5408.350997880201;5532.585056120807;5537.972310138423;5582.330120385498;5615.213870058195;3434.866494056955;3439.174598096489;3481.1993241554583;3491.2493813830147;3506.7545271830727;6337.904931426032;6390.360963903572;6432.388923052396;6438.323975982548;6446.838829029218;6641.86716265672;6672.866542669217;6734.292899245509;6759.5944243344575;6793.660316984131;6223.216693803775;6233.501319894488;6312.715927783261;6333.36666333363;6386.010168423902;6411.166548348685;6503.414692677682;6541.247756508063;6588.670566471561;6619.801405957829;6518.478521718346;6519.511036672364;6522.380373864476;6529.216494020748;6544.280129185881;6181.443670069535;6188.319284878569;6194.349593292639;6277.323394071446;6354.714037868214;5292.894142117213;5305.12465443213;5390.19147127934;5402.324709411324;5425.321834770653;6282.277538083179;6516.478681705357;6569.9014514783275;6611.570247933869;6635.2037755469655;6258.499320054294;6295.213098362705;6297.716602752978;6365.61806291624;6413.551051426355;6366.140571022853;6388.977763557808;6397.616143031433;6419.133416988647;6428.614283143026;6249.281332646622;6358.936410635834;6383.170187875547;6384.521160912857;6408.102865598913;6407.9038814418755;6432.163818703539;6505.642189679424;6530;6551.082848401323;6222.81331560054;6302.779402720883;6313.936860631335;6315.463185629273;6330.683465826783 18:48:57 INFO - PID 3823 | |5;yui.html;900.8198360327937;906.1844340093915;924.0574613893824;927.1748144151702;934.7102398256538;19760.802391976093;20584.279550215728;20763.68854467184;20770.169193232254;20797.896010519966;18500.0749962502;18752.343667971625;19105.14026868791;19338.323158689447;19444.51388715283;947.8862536495631;974.2741657465162;976.6337623391229;977.2524019125375;981.1071924548662;925.051821882569;966.5505539923934;967.6806653804227;970.180197732918;971.5190980464645;986.8322385194534;1020.3571749797617;1024.87701475823;1027.8201314769922;1028.4240825137947;1004.5931397783909;1009.0766948242386;1015.532854886753;1016.6594190946042;1020.3265844542603;732.5677850068486;739.7078154129089;741.3068780690019;741.907261592301;742.4283301857594;19193.136308866135;20433.080511377022;20748.170073197052;20789.792102078936;20798.896005519877;21424.57150856974;21505.784942150534;21962.121515139374;21963.450913727123;22000.88999555008;17259.395921142826;17401.91299043497;17455.127243637893;17466.388676396266;17479.563010924703;3653.6163702811086;3659.0669379308106;3664.8900532984053;3665.7067434605433;3690.3910854708943;141049;141060;143367;144315.55684443127;144890;16743.24655390486;16935.830641693672;16943.491695249162;17058.914705426392;17119.91440042792;31334;31581.210469738442;31878.681213188036;31963.520547191813;32157;8241.629126689193;8347.332213422911;8358.080611132771;8374.53940033304;8504;4192.601702838226;4263.467066616673;4266.509351424564;4267.103908179255;4273.230818452708;5573.972130139324;5628.409017053233;5641.746121424546;5645.633033852869;5717.742701578439;1056.1920131099714;1065.4938904020528;1074.570171931218;1078.654830454247;1079.1582565598846;694.6943344928216;696.0498918975663;703.3669697272496;703.7642389799474;706.4313227851629;11840.526378944918;11905;11974.22167559106;12929.54746583884;13105.475780968654;664.6045602866287;668.3316683316683;687.8761822871832;693.2478261086677;703.0684343245221;5342.171963345688;5472.288602481652;5498.972505137449;5548.0845660466375;5598.076317407664;116539.41730291295;119582;122544.38727806482;122579.38710306391;124225.37887310686;107976;108476.4576177114;109762;109933.45033274783;110142;94172;97957.99999999857;98083.50958245163;98427;99050;10209.285350025724;10319.690409287734;10366.637167699095;10378.896211037792;10482.895171048192;44035;44143.11713765771;44384;44549.55450445454;45306.77346613246;53466.465335347704;55068.724656376464;56264.15603765865;56413.71793141008;57707.4229257702;15174.013689110172;15609.375624974873;15717.135557543987;15793.763093553376;15905;16966.915165424092;17089.401870934456;17344.47966561005;17543;17742.645147097242;15442.45951391696;15724.76412853831;15858.207089645703;16124;16203.75694364562;14750.63123421923;14806.62983425423;14870.479533216285;14909.776353354926;15001.399944002555;14097.788533172215;14341.713165736419;14423.78364324557;14558.272086395426;14574.271286435847;11630.94184529072;11661.591844285409;11884;11898.524059037542;12124.333161676192;11701.414929253673;11729.589464368706;11823.46794394237;11902.583409580624;12066.155369124077 18:48:57 INFO - PID 3823 | __end_tp_report 18:48:57 INFO - PID 3823 | __start_cc_report 18:48:57 INFO - PID 3823 | _x_x_mozilla_cycle_collect,290 18:48:57 INFO - PID 3823 | __end_cc_report 18:48:57 INFO - PID 3823 | __startTimestamp1505958537304__endTimestamp 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | ------- Summary: start ------- 18:48:57 INFO - PID 3823 | Number of tests: 6 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#0] dojo.html Cycles:165 Average:6170.29 Median:6421.81 stddev:747.47 (11.6%) stddev-sans-first:709.27 18:48:57 INFO - PID 3823 | Values: 3076.8 3084.1 3084.6 3112.8 3128.2 6100.5 6107.5 6153.6 6158.2 6187.1 6203.5 6212.4 6277.9 6280.7 6304.8 5720.1 5785.9 5797.8 5802.8 5840.3 5725.1 5791.6 5813.5 5850.1 5933.6 5829.7 5858.9 5872.0 5886.9 5888.3 5554.8 5672.1 5676.0 5784.2 5810.4 5700.0 5758.9 5766.9 5798.2 5822.8 6155.3 6167.4 6273.9 6325.1 6330.3 6694.4 6697.7 6698.6 6745.2 6809.6 6776.7 6784.9 6850.0 6866.4 6873.1 6211.1 6239.4 6241.1 6277.0 6334.0 6741.4 6928.2 6942.4 6989.1 7017.3 6483.2 6552.6 6654.6 6670.2 6681.2 6678.3 6733.3 6742.8 6762.3 6836.0 6648.2 6666.9 6688.2 6774.7 6777.4 6382.1 6474.7 6593.5 6690.0 6816.1 5294.5 5313.2 5330.7 5342.5 5344.6 4550.1 4597.1 4634.5 4691.6 4700.0 5647.4 5738.9 5775.6 5810.1 5843.7 6743.5 6756.3 6796.6 6831.8 6836.9 5588.1 5647.5 5700.3 5709.8 5794.0 5711.2 5731.3 5732.0 5732.7 5753.0 6391.1 6430.3 6454.3 6534.4 6547.5 6701.3 6764.0 6796.3 6806.4 6831.1 6403.3 6461.7 6471.5 6473.0 6532.5 6476.6 6573.4 6573.5 6612.7 6671.2 6606.0 6700.5 6719.4 6748.8 6753.5 6421.8 6447.6 6474.3 6474.6 6475.4 6565.5 6617.9 6619.8 6621.8 6644.9 6476.0 6479.5 6488.1 6495.7 6566.2 6545.6 6582.3 6604.4 6680.9 6686.0 6327.5 6388.1 6391.5 6392.9 6427.0 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#1] ext.html Cycles:180 Average:36917.36 Median:36306.96 stddev:51299.93 (141.3%) stddev-sans-first:51372.47 18:48:57 INFO - PID 3823 | Values: 880.6 901.9 912.4 912.8 914.2 39168.4 40211.0 43056.1 43179.8 43317.0 39529.6 39557.4 39563.6 39847.0 40097.0 7305.5 7348.9 7377.5 7387.7 7833.7 7903.2 7998.6 8000.7 8007.6 8013.6 7997.8 8031.1 8044.2 8049.1 8052.2 7951.6 8001.6 8012.8 8015.1 8027.9 7811.4 7845.1 7847.1 7857.1 7884.1 38507.0 38558.8 38726.0 38838.4 39051.4 3464.2 3484.3 3498.9 3501.9 3515.6 32695.3 32951.2 32956.0 33005.0 33096.5 5133.9 5135.8 5163.8 5207.1 5211.1 185987.0 186202.0 186696.0 186733.0 187476.0 42614.8 43040.0 49584.3 49592.8 49688.0 51529.5 53123.7 53221.0 53358.7 53450.0 16352.8 16370.3 16388.9 16406.4 16451.4 13524.3 13668.0 13685.0 13715.4 13724.2 14367.6 14381.1 14518.3 14627.7 14646.6 5438.9 5455.9 5474.7 5570.1 5661.0 961.0 975.4 975.8 976.5 981.1 19717.6 20135.0 20213.0 20356.0 20718.0 1068.9 1074.8 1076.3 1087.1 1092.7 11263.9 11364.2 11504.0 11593.5 11750.3 214952.0 216268.0 216622.0 216873.0 217459.0 52711.2 52775.0 52986.5 53234.7 53261.0 1427.2 1479.4 1487.8 1491.1 1497.5 21305.1 21610.5 21649.0 21706.8 22167.8 37956.2 38125.6 38188.6 38307.6 38313.2 189338.0 189947.0 190119.0 190242.0 191097.0 30351.0 34044.3 34813.5 35755.0 36330.6 44118.3 44591.6 44617.6 44941.6 45169.0 32326.2 32445.7 32564.2 32635.7 32830.2 32321.5 32522.7 32710.5 32770.7 32962.0 36261.6 36283.3 36505.5 36706.6 37413.1 31874.0 31993.4 32201.8 32250.4 32349.4 22582.7 22611.9 22682.1 22882.9 23138.7 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#2] jquery.html Cycles:180 Average:34955.55 Median:22890.10 stddev:84343.29 (368.5%) stddev-sans-first:84540.39 18:48:57 INFO - PID 3823 | Values: 573.9 584.2 596.4 600.9 628.3 22036.7 22788.5 22897.0 23010.0 23027.8 23507.6 24320.8 24364.9 24448.1 24507.6 6630.4 6681.3 6684.2 6723.8 6786.0 6639.5 6741.7 6745.9 6747.7 6749.0 6267.8 6485.6 6495.2 6522.8 6525.6 6418.8 6454.3 6495.3 6509.1 6513.9 5918.9 5990.4 6024.8 6033.7 6128.1 27534.2 28034.7 28042.3 28067.2 28091.6 2055.7 2073.9 2086.9 2090.6 2099.7 18424.2 18911.3 19244.7 19264.1 19283.9 2415.4 2452.8 2463.1 2471.2 2485.2 118189.4 123823.0 136634.0 138105.0 141126.3 14804.3 14927.6 14955.5 15002.0 15086.8 22883.2 23145.2 23147.2 23225.0 23345.9 9973.2 10142.9 10192.8 10201.5 10526.9 9438.1 9529.4 9575.6 9681.5 9742.7 8769.8 8994.7 9016.7 9061.5 9065.4 2774.5 2783.8 2845.7 2873.8 2896.2 945.6 958.2 971.6 972.0 975.6 18305.8 18617.2 18698.9 18877.8 18916.2 1027.7 1028.3 1036.1 1036.2 1042.5 10501.5 10634.3 10734.4 10763.0 10973.4 486275.0 498453.0 505075.0 507411.0 513780.0 113854.0 114558.0 114988.0 116453.8 116517.4 1139.8 1176.9 1250.0 1259.1 1272.5 20122.6 20144.0 20297.6 20772.3 20784.2 56330.4 57822.7 58947.4 59052.4 59917.0 74753.0 75066.6 75963.2 76480.0 76565.2 14473.0 14511.9 14529.3 14545.6 14570.2 22272.9 22436.6 22514.9 22585.9 22907.5 17678.5 17782.8 18173.5 18175.3 18183.6 16000.1 16133.6 16238.9 16370.6 16421.8 17679.7 18549.6 18701.7 18820.5 18831.7 14330.5 14499.3 14509.2 14521.0 14523.9 15771.8 16662.8 16846.4 16849.7 17008.7 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#3] mootools.html Cycles:180 Average:9479.35 Median:2746.61 stddev:6720.84 (244.7%) stddev-sans-first:6711.43 18:48:57 INFO - PID 3823 | Values: 1276.3 1323.8 1329.8 1354.4 1361.1 15204.2 15830.3 15892.4 15951.8 15972.3 15254.8 15289.4 15352.4 15423.8 15455.1 2797.7 2825.7 2864.4 2888.4 2895.6 2713.8 2738.8 2763.9 2769.6 2773.2 2681.2 2746.1 2761.0 2875.7 2885.7 2825.5 2859.2 2859.2 2865.1 2866.5 2683.0 2747.2 2747.3 2759.4 2768.9 14518.2 14852.0 14889.4 14969.9 14996.9 8217.8 8672.4 8726.6 8736.0 8743.6 5743.0 5878.3 5902.8 5975.3 5996.5 1994.7 2007.9 2016.7 2019.4 2068.3 27208.3 27512.9 27536.2 27562.4 27601.0 6961.3 7675.5 7867.4 7907.6 8037.1 17002.1 17010.5 17115.4 17172.1 17206.0 8028.6 8092.8 8544.4 8863.3 8872.9 7676.0 7745.7 7770.2 7879.0 7883.4 5296.3 5432.4 5462.5 5466.8 5470.9 1674.8 1677.5 1698.3 1700.8 1709.6 852.4 856.4 867.5 870.4 880.0 7260.3 7387.3 7488.6 7549.8 7554.5 714.1 720.5 726.8 734.8 739.0 5096.3 5196.1 5242.1 5327.8 5395.2 21223.2 21384.1 21707.0 21727.6 21764.7 20648.3 21390.4 21419.5 21696.7 22023.7 11215.7 11379.4 11380.1 11543.5 11608.7 7968.0 8209.9 8231.2 8247.2 8279.6 17971.5 18452.7 18789.7 18968.9 19143.3 18384.3 19196.3 19271.9 19455.2 19634.4 11217.3 11314.7 11324.0 11327.2 11353.4 13674.5 13997.8 14071.9 14080.1 14091.2 11320.8 11521.7 11611.9 11632.1 12009.5 11059.3 11107.4 11217.4 11346.8 11465.6 10193.5 10320.2 10365.1 10381.1 10415.5 7232.6 7243.4 7331.5 7392.7 7475.0 7075.8 7146.6 7168.2 7232.6 7307.1 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#4] prototype.html Cycles:180 Average:5466.66 Median:6162.74 stddev:1473.23 (23.9%) stddev-sans-first:1429.38 18:48:57 INFO - PID 3823 | Values: 497.9 507.4 530.3 535.0 536.1 6269.7 6275.7 6288.5 6313.3 6370.5 6161.6 6163.8 6254.7 6284.3 6286.7 5120.4 5145.3 5147.8 5160.2 5161.9 5043.8 5060.9 5064.6 5093.9 5102.4 4727.8 4812.2 4875.5 4915.6 4954.4 4875.2 4888.6 4889.5 4931.6 4966.0 4318.8 4382.7 4387.2 4397.9 4402.9 5857.3 5923.2 5984.0 6073.7 6089.8 5542.8 5546.9 5593.2 5595.0 5640.0 6377.6 6409.3 6435.5 6481.4 6484.5 4566.4 4774.3 4798.9 4812.4 4838.4 6544.7 6566.2 6644.5 6665.4 6775.5 5230.0 5295.7 5336.0 5340.7 5395.4 5416.5 5451.9 5463.4 5467.0 5469.5 5756.9 5817.3 5834.5 5856.2 5860.9 6471.0 6515.4 6540.2 6545.3 6585.5 1878.2 1880.5 1903.2 1905.7 1911.1 1375.2 1428.3 1436.2 1436.8 1449.8 5512.8 5543.4 5569.9 5587.2 5670.1 6430.8 6451.9 6464.5 6472.0 6498.9 5408.4 5532.6 5538.0 5582.3 5615.2 3434.9 3439.2 3481.2 3491.2 3506.8 6337.9 6390.4 6432.4 6438.3 6446.8 6641.9 6672.9 6734.3 6759.6 6793.7 6223.2 6233.5 6312.7 6333.4 6386.0 6411.2 6503.4 6541.2 6588.7 6619.8 6518.5 6519.5 6522.4 6529.2 6544.3 6181.4 6188.3 6194.3 6277.3 6354.7 5292.9 5305.1 5390.2 5402.3 5425.3 6282.3 6516.5 6569.9 6611.6 6635.2 6258.5 6295.2 6297.7 6365.6 6413.6 6366.1 6389.0 6397.6 6419.1 6428.6 6249.3 6358.9 6383.2 6384.5 6408.1 6407.9 6432.2 6505.6 6530.0 6551.1 6222.8 6302.8 6313.9 6315.5 6330.7 18:48:57 INFO - PID 3823 | 18:48:57 INFO - PID 3823 | [#5] yui.html Cycles:180 Average:24415.91 Median:20096.94 stddev:35612.73 (177.2%) stddev-sans-first:35668.86 18:48:57 INFO - PID 3823 | Values: 900.8 906.2 924.1 927.2 934.7 19760.8 20584.3 20763.7 20770.2 20797.9 18500.1 18752.3 19105.1 19338.3 19444.5 947.9 974.3 976.6 977.3 981.1 925.1 966.6 967.7 970.2 971.5 986.8 1020.4 1024.9 1027.8 1028.4 1004.6 1009.1 1015.5 1016.7 1020.3 732.6 739.7 741.3 741.9 742.4 19193.1 20433.1 20748.2 20789.8 20798.9 21424.6 21505.8 21962.1 21963.5 22000.9 17259.4 17401.9 17455.1 17466.4 17479.6 3653.6 3659.1 3664.9 3665.7 3690.4 141049.0 141060.0 143367.0 144315.6 144890.0 16743.2 16935.8 16943.5 17058.9 17119.9 31334.0 31581.2 31878.7 31963.5 32157.0 8241.6 8347.3 8358.1 8374.5 8504.0 4192.6 4263.5 4266.5 4267.1 4273.2 5574.0 5628.4 5641.7 5645.6 5717.7 1056.2 1065.5 1074.6 1078.7 1079.2 694.7 696.0 703.4 703.8 706.4 11840.5 11905.0 11974.2 12929.5 13105.5 664.6 668.3 687.9 693.2 703.1 5342.2 5472.3 5499.0 5548.1 5598.1 116539.4 119582.0 122544.4 122579.4 124225.4 107976.0 108476.5 109762.0 109933.5 110142.0 94172.0 97958.0 98083.5 98427.0 99050.0 10209.3 10319.7 10366.6 10378.9 10482.9 44035.0 44143.1 44384.0 44549.6 45306.8 53466.5 55068.7 56264.2 56413.7 57707.4 15174.0 15609.4 15717.1 15793.8 15905.0 16966.9 17089.4 17344.5 17543.0 17742.6 15442.5 15724.8 15858.2 16124.0 16203.8 14750.6 14806.6 14870.5 14909.8 15001.4 14097.8 14341.7 14423.8 14558.3 14574.3 11630.9 11661.6 11884.0 11898.5 12124.3 11701.4 11729.6 11823.5 11902.6 12066.2 18:48:57 INFO - PID 3823 | -------- Summary: end -------- 18:48:57 INFO - PID 3823 | 18:48:58 INFO - TEST-INFO | 3823: exit 0 18:48:58 INFO - TEST-OK | dromaeo_css | took 1114942ms 18:48:58 INFO - TEST-START | kraken 18:48:58 INFO - Initialising browser for kraken test... 18:48:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44931/getInfo.html -profile /tmp/tmpmW_HQ1/profile 18:48:58 INFO - TEST-INFO | started process 4198 (/builds/slave/test/build/application/firefox/firefox http://localhost:44931/getInfo.html) 18:49:03 INFO - TEST-INFO | 4198: exit 0 18:49:04 INFO - Browser initialized. 18:49:04 INFO - Running cycle 1/1 for kraken test... 18:49:04 INFO - Using env: {'DISPLAY': ':0', 18:49:04 INFO - 'HOME': '/home/cltbld', 18:49:04 INFO - 'JSGC_DISABLE_POISONING': '1', 18:49:04 INFO - 'LANG': 'en_US.UTF-8', 18:49:04 INFO - 'LANGUAGE': 'en_US:en', 18:49:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:49:04 INFO - 'LOGNAME': 'cltbld', 18:49:04 INFO - 'MAIL': '/var/mail/cltbld', 18:49:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:49:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:49:04 INFO - 'MOZ_ACCELERATED': '1', 18:49:04 INFO - 'MOZ_CRASHREPORTER': '1', 18:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:49:04 INFO - 'MOZ_NO_REMOTE': '1', 18:49:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:49:04 INFO - 'MOZ_WEBRENDER': '1', 18:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:04 INFO - 'NO_EM_RESTART': '1', 18:49:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:04 INFO - 'PWD': '/builds/slave/test', 18:49:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:49:04 INFO - 'RUST_BACKTRACE': 'full', 18:49:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:49:04 INFO - 'SHELL': '/bin/bash', 18:49:04 INFO - 'SHLVL': '1', 18:49:04 INFO - 'TERM': 'linux', 18:49:04 INFO - 'TMOUT': '86400', 18:49:04 INFO - 'USER': 'cltbld', 18:49:04 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101', 18:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:04 INFO - '_': '/tools/buildbot/bin/python'} 18:49:04 INFO - TEST-INFO | started process 4444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmW_HQ1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:04 INFO - PID 4444 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:49:05 INFO - PID 4444 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:49:05 INFO - PID 4444 | 18:49:05 INFO - PID 4444 | (/builds/slave/test/build/application/firefox/firefox:4504): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:49:05 INFO - PID 4444 | 18:49:15 INFO - PID 4444 | RSS: Main: 206639104 18:49:15 INFO - PID 4444 | 18:49:18 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44931/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:19 INFO - PID 4444 | RSS: Main: 207941632 18:49:19 INFO - PID 4444 | 18:49:21 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44931/tests/kraken/driver.html?testName=audio-dft) 18:49:22 INFO - PID 4444 | RSS: Main: 207835136 18:49:22 INFO - PID 4444 | 18:49:25 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44931/tests/kraken/driver.html?testName=audio-fft) 18:49:26 INFO - PID 4444 | RSS: Main: 205643776 18:49:26 INFO - PID 4444 | 18:49:28 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44931/tests/kraken/driver.html?testName=audio-oscillator) 18:49:28 INFO - PID 4444 | RSS: Main: 205160448 18:49:28 INFO - PID 4444 | 18:49:31 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44931/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:31 INFO - PID 4444 | RSS: Main: 205189120 18:49:31 INFO - PID 4444 | 18:49:34 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44931/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:35 INFO - PID 4444 | RSS: Main: 208101376 18:49:35 INFO - PID 4444 | 18:49:39 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44931/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:39 INFO - PID 4444 | RSS: Main: 207777792 18:49:39 INFO - PID 4444 | 18:49:42 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44931/tests/kraken/driver.html?testName=json-parse-financial) 18:49:43 INFO - PID 4444 | RSS: Main: 208019456 18:49:43 INFO - PID 4444 | 18:49:45 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44931/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:45 INFO - PID 4444 | RSS: Main: 208564224 18:49:45 INFO - PID 4444 | 18:49:47 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:48 INFO - PID 4444 | RSS: Main: 205336576 18:49:48 INFO - PID 4444 | 18:49:50 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:50 INFO - PID 4444 | RSS: Main: 205254656 18:49:50 INFO - PID 4444 | 18:49:53 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:49:54 INFO - PID 4444 | RSS: Main: 205512704 18:49:54 INFO - PID 4444 | 18:49:57 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:49:57 INFO - PID 4444 | RSS: Main: 205676544 18:49:57 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | Cycle 1(1): loaded http://localhost:44931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44931/tests/kraken/driver.html?testName=ai-astar) 18:49:59 INFO - PID 4444 | RSS: Main: 207544320 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | __start_tp_report 18:49:59 INFO - PID 4444 | _x_x_mozilla_page_load 18:49:59 INFO - PID 4444 | _x_x_mozilla_page_load_details 18:49:59 INFO - PID 4444 | |i|pagename|runs| 18:49:59 INFO - PID 4444 | |0;ai-astar;109;109;110;108;115;106;108;114;107;111 18:49:59 INFO - PID 4444 | |1;audio-beat-detection;120;129;120;125;120;150;134;120;119;120 18:49:59 INFO - PID 4444 | |2;audio-dft;167;170;172;172;164;170;170;162;163;164 18:49:59 INFO - PID 4444 | |3;audio-fft;75;81;87;80;77;73;82;75;76;78 18:49:59 INFO - PID 4444 | |4;audio-oscillator;79;79;88;84;79;85;79;88;80;80 18:49:59 INFO - PID 4444 | |5;imaging-gaussian-blur;119;116;118;116;120;116;114;116;117;114 18:49:59 INFO - PID 4444 | |6;imaging-darkroom;153;158;151;152;149;148;148;149;158;156 18:49:59 INFO - PID 4444 | |7;imaging-desaturate;99;97;99;101;95;94;94;99;95;94 18:49:59 INFO - PID 4444 | |8;json-parse-financial;56;54;50;50;53;61;55;53;54;53 18:49:59 INFO - PID 4444 | |9;json-stringify-tinderbox;38;38;49;42;38;40;39;42;38;44 18:49:59 INFO - PID 4444 | |10;stanford-crypto-aes;77;73;81;78;77;76;76;75;76;75 18:49:59 INFO - PID 4444 | |11;stanford-crypto-ccm;114;114;115;124;116;117;111;117;109;115 18:49:59 INFO - PID 4444 | |12;stanford-crypto-pbkdf2;174;176;176;167;172;167;167;168;175;176 18:49:59 INFO - PID 4444 | |13;stanford-crypto-sha256-iterative;67;64;65;70;66;69;68;68;65;67 18:49:59 INFO - PID 4444 | __end_tp_report 18:49:59 INFO - PID 4444 | __start_cc_report 18:49:59 INFO - PID 4444 | _x_x_mozilla_cycle_collect,759 18:49:59 INFO - PID 4444 | __end_cc_report 18:49:59 INFO - PID 4444 | __startTimestamp1505958599740__endTimestamp 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | ------- Summary: start ------- 18:49:59 INFO - PID 4444 | Number of tests: 14 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#0] ai-astar Cycles:10 Average:109.70 Median:109.50 stddev:2.91 (2.7%) stddev-sans-first:3.07 18:49:59 INFO - PID 4444 | Values: 109.0 109.0 110.0 108.0 115.0 106.0 108.0 114.0 107.0 111.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:122.50 stddev:9.88 (8.1%) stddev-sans-first:10.26 18:49:59 INFO - PID 4444 | Values: 120.0 129.0 120.0 125.0 120.0 150.0 134.0 120.0 119.0 120.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#2] audio-dft Cycles:10 Average:167.40 Median:170.00 stddev:3.86 (2.3%) stddev-sans-first:4.10 18:49:59 INFO - PID 4444 | Values: 167.0 170.0 172.0 172.0 164.0 170.0 170.0 162.0 163.0 164.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#3] audio-fft Cycles:10 Average:78.40 Median:79.00 stddev:4.17 (5.3%) stddev-sans-first:4.24 18:49:59 INFO - PID 4444 | Values: 75.0 81.0 87.0 80.0 77.0 73.0 82.0 75.0 76.0 78.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:82.00 stddev:3.78 (4.6%) stddev-sans-first:3.84 18:49:59 INFO - PID 4444 | Values: 79.0 79.0 88.0 84.0 79.0 85.0 79.0 88.0 80.0 80.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:116.50 stddev:1.96 (1.7%) stddev-sans-first:1.87 18:49:59 INFO - PID 4444 | Values: 119.0 116.0 118.0 116.0 120.0 116.0 114.0 116.0 117.0 114.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#6] imaging-darkroom Cycles:10 Average:152.20 Median:152.50 stddev:3.94 (2.6%) stddev-sans-first:4.17 18:49:59 INFO - PID 4444 | Values: 153.0 158.0 151.0 152.0 149.0 148.0 148.0 149.0 158.0 156.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.00 stddev:2.63 (2.7%) stddev-sans-first:2.65 18:49:59 INFO - PID 4444 | Values: 99.0 97.0 99.0 101.0 95.0 94.0 94.0 99.0 95.0 94.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:3.14 (5.8%) stddev-sans-first:3.24 18:49:59 INFO - PID 4444 | Values: 56.0 54.0 50.0 50.0 53.0 61.0 55.0 53.0 54.0 53.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.00 stddev:3.58 (8.7%) stddev-sans-first:3.66 18:49:59 INFO - PID 4444 | Values: 38.0 38.0 49.0 42.0 38.0 40.0 39.0 42.0 38.0 44.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:76.50 stddev:2.12 (2.8%) stddev-sans-first:2.24 18:49:59 INFO - PID 4444 | Values: 77.0 73.0 81.0 78.0 77.0 76.0 76.0 75.0 76.0 75.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#11] stanford-crypto-ccm Cycles:10 Average:115.20 Median:115.50 stddev:3.99 (3.5%) stddev-sans-first:4.21 18:49:59 INFO - PID 4444 | Values: 114.0 114.0 115.0 124.0 116.0 117.0 111.0 117.0 109.0 115.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:174.50 stddev:4.10 (2.4%) stddev-sans-first:4.28 18:49:59 INFO - PID 4444 | Values: 174.0 176.0 176.0 167.0 172.0 167.0 167.0 168.0 175.0 176.0 18:49:59 INFO - PID 4444 | 18:49:59 INFO - PID 4444 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.91 (2.8%) stddev-sans-first:2.03 18:49:59 INFO - PID 4444 | Values: 67.0 64.0 65.0 70.0 66.0 69.0 68.0 68.0 65.0 67.0 18:49:59 INFO - PID 4444 | -------- Summary: end -------- 18:49:59 INFO - PID 4444 | 18:50:00 INFO - PID 4444 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:50:00 INFO - TEST-INFO | 4444: exit 0 18:50:00 INFO - TEST-OK | kraken | took 62463ms 18:50:00 INFO - SUITE-END | took 1177s 18:50:00 INFO - Completed test suite (00:19:37) 18:50:00 INFO - javascript benchmark 18:50:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8516.866957303131, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.8307743074124, 3084.0747775667296, 3084.6298444186696, 3112.782105252632, 3128.202308411356, 6100.481459075984, 6107.450329470346, 6153.600015998963, 6158.168647232622, 6187.133801267826, 6203.534734894878, 6212.409821067003, 6277.905831412532, 6280.748770049193, 6304.779332723356, 5720.084786434171, 5785.884282314351, 5797.797077102302, 5802.82591522255, 5840.269966254218, 5725.14122881569, 5791.594588378815, 5813.505852002585, 5850.122481627769, 5933.584649074566, 5829.737661805208, 5858.853528661775, 5872.0, 5886.88226235475, 5888.32284287306, 5554.833354999357, 5672.06410942194, 5676.0, 5784.1902133701315, 5810.418958104176, 5700.0, 5758.913616295759, 5766.913496297559, 5798.246227990377, 5822.767089316422, 6155.261368635747, 6167.44492995628, 6273.87452250957, 6325.051242313643, 6330.303666596671, 6694.397504224595, 6697.732090716366, 6698.5980841149685, 6745.224299205605, 6809.557378770364, 6776.728930842761, 6784.864302713968, 6850.0, 6866.382025617669, 6873.072135261726, 6211.099390588384, 6239.438450539428, 6241.126242326078, 6277.027060805583, 6334.049892516159, 6741.393274605309, 6928.189495777302, 6942.409895158867, 6989.091418115612, 7017.298270172942, 6483.189601299838, 6552.6068435894, 6654.600723956578, 6670.166229221348, 6681.231658359254, 6678.265390807007, 6733.293004234485, 6742.764003259862, 6762.323767623297, 6835.974603809468, 6648.235452922879, 6666.899996500057, 6688.230853451818, 6774.661266936732, 6777.390034896883, 6382.0745991831855, 6474.708638111297, 6593.5384523083885, 6690.0, 6816.07982922309, 5294.470552944675, 5313.2030195470215, 5330.680159190461, 5342.519173274346, 5344.625876188629, 4550.13547425988, 4597.149527337453, 4634.490206077328, 4691.62467002639, 4700.036492519025, 5647.350554686182, 5738.942610573924, 5775.595708300462, 5810.099434587646, 5843.678597677169, 6743.460523158234, 6756.324367563303, 6796.592204467748, 6831.760888368884, 6836.863262734718, 5588.133839254922, 5647.463490968352, 5700.287464066992, 5709.771609135588, 5794.0439827428, 5711.200431939532, 5731.283589551306, 5731.971340143356, 5732.71336433185, 5753.0, 6391.1371964785085, 6430.260520040161, 6454.2577603575255, 6534.379233972765, 6547.476201903932, 6701.32986701326, 6763.966180169067, 6796.320367963362, 6806.421454176252, 6831.077804496329, 6403.263624683034, 6461.709223085067, 6471.514636402195, 6473.0, 6532.477401807749, 6476.6114033158165, 6573.441257493167, 6573.506986976092, 6612.735490580515, 6671.232808227116, 6606.0, 6700.4639628830555, 6719.361660642231, 6748.831279217862, 6753.493487988322, 6421.8073457796345, 6447.580907241015, 6474.255460622088, 6474.5791523550815, 6475.449586785176, 6565.540412171103, 6617.900731489128, 6619.801405957829, 6621.768238111643, 6644.933550664431, 6475.967620161869, 6479.481641468766, 6488.091667166506, 6495.707693153725, 6566.212054553484, 6545.574537655038, 6582.275949645439, 6604.3725846044545, 6680.899786503109, 6685.963675630091, 6327.525435592441, 6388.073729309317, 6391.520635952415, 6392.9360706392345, 6427.0], "value": 6112.367649735311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [880.5773228850152, 901.9357158552907, 912.4388501071843, 912.7991841794807, 914.2320450821309, 39168.41247381292, 40211.0, 43056.13887722244, 43179.78410107949, 43317.0, 39529.604703953024, 39557.40663890044, 39563.60436395635, 39847.0, 40097.0, 7305.525140865853, 7348.853022939538, 7377.487615038931, 7387.704491820321, 7833.725819596315, 7903.209679032108, 7998.64006119726, 8000.67997280111, 8007.639656215484, 8013.599320034004, 7997.760067197964, 8031.076426210973, 8044.195580441939, 8049.074356448996, 8052.154523775007, 7951.562664053504, 8001.599920004006, 8012.759617211493, 8015.118336982927, 8027.879581806277, 7811.375089992787, 7845.137034926153, 7847.1368149503505, 7857.0964339101165, 7884.053913530356, 38507.0, 38558.80720596379, 38726.0, 38838.41742373867, 39051.41422878659, 3464.185916309665, 3484.26830365621, 3498.930021399558, 3501.8949431517094, 3515.6308587598446, 32695.346093078006, 32951.17622059444, 32956.0, 33005.0, 33096.50355244673, 5133.897322053538, 5135.820246291362, 5163.8123231656555, 5207.140821764404, 5211.140161873286, 185987.0, 186202.0, 186696.0, 186733.0, 187476.0, 42614.78692606517, 43040.0, 49584.256236156485, 49592.75203623959, 49688.0, 51529.48470515247, 53123.73438132785, 53221.0, 53358.733206333716, 53450.0, 16352.75470867938, 16370.263338149813, 16388.918055409646, 16406.425775097814, 16451.42420015294, 13524.32378381077, 13668.0, 13685.04204705681, 13715.382807773674, 13724.176549407068, 14367.640808979859, 14381.137131772128, 14518.346674399678, 14627.707445851023, 14646.633834154018, 5438.8912221755345, 5455.944670984261, 5474.698891561003, 5570.080936645408, 5661.0, 960.9519524023771, 975.3513913247752, 975.8438649815995, 976.5215044628081, 981.1316984468797, 19717.60564788696, 20135.0, 20212.98935053271, 20356.0, 20718.0, 1068.8663917010374, 1074.8441475985937, 1076.2896488317672, 1087.0868470484832, 1092.6503518873883, 11263.94368028171, 11364.204505684524, 11504.022158116486, 11593.478293476814, 11750.29498230109, 214952.0, 216268.0, 216622.0, 216873.0, 217459.0, 52711.20933186005, 52775.0, 52986.47013529815, 53234.73382633062, 53261.0, 1427.1722401007391, 1479.4304192886032, 1487.8363380028188, 1491.1202390589604, 1497.5432493089713, 21305.14779408806, 21610.518076073546, 21649.0, 21706.782932170478, 22167.778322217215, 37956.24087518179, 38125.61874381221, 38188.618113818506, 38307.61692383152, 38313.23373532458, 189338.0, 189947.0, 190119.0, 190242.0, 191097.0, 30351.0, 34044.31911361808, 34813.47779783356, 35755.0, 36330.63669363378, 44118.33822492729, 44591.55408445874, 44617.55382446134, 44941.550584493736, 45169.0, 32326.19184520406, 32445.67554324521, 32564.185895351857, 32635.673643263264, 32830.17924551905, 32321.51517727189, 32522.674773251965, 32710.509342359408, 32770.67229327772, 32962.0, 36261.63738362688, 36283.27433451264, 36505.45241821428, 36706.63293367032, 37413.064673383386, 31874.0, 31993.36013279768, 32201.838990805834, 32250.35499290048, 32349.35301294008, 22582.661260080782, 22611.886940565193, 22682.092716291165, 22882.885585571967, 23138.652920206547], "value": 17104.667243787895, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.945475179858, 584.2113147251214, 596.4006173795337, 600.8527910661888, 628.318274671981, 22036.669449958245, 22788.544229115407, 22897.0, 23010.0, 23027.76972230277, 23507.647385289234, 24320.811126998567, 24364.878175609098, 24448.14431494898, 24507.632385514185, 6630.436412904897, 6681.298463661318, 6684.231313398948, 6723.798286051427, 6785.966070169642, 6639.502037347218, 6741.730330786787, 6745.898811517831, 6747.662616869162, 6748.966255168717, 6267.843303917416, 6485.6108633482145, 6495.220573531183, 6522.836929076763, 6525.575837570566, 6418.775342862978, 6454.257760357572, 6495.317991610884, 6509.088727578142, 6513.902291465632, 5918.940810591882, 5990.371011043821, 6024.849378765566, 6033.668148251844, 6128.080787881809, 27534.173974780788, 28034.71965280362, 28042.298942526395, 28067.157985260474, 28091.57862632062, 2055.7122002919605, 2073.9481512962147, 2086.9165233390645, 2090.560982193741, 2099.6955441460905, 18424.17091230898, 18911.33810316646, 19244.71132933007, 19264.133114009903, 19283.903580482147, 2415.3961509622595, 2452.754724527551, 2463.0763463701114, 2471.2462698876834, 2485.2171565956664, 118189.40905295504, 123823.0, 136634.0, 138105.0, 141126.29436852853, 14804.297505786757, 14927.581879721409, 14955.486068234883, 15002.0, 15086.849131508545, 22883.199088032172, 23145.189918353113, 23147.189848355563, 23225.0, 23345.883270583538, 9973.202143828476, 10142.898571014342, 10192.847107293397, 10201.540930658139, 10526.894676059057, 9438.103380179009, 9529.428234305964, 9575.616975320909, 9681.51592420376, 9742.659006934866, 8769.780755481037, 8994.685186018458, 9016.729498115066, 9061.501617411106, 9065.365424420359, 2774.542200536929, 2783.8051336406256, 2845.7438830505353, 2873.813202141854, 2896.189067061226, 945.5697657565792, 958.2334132693817, 971.6016433262329, 972.0036962113749, 975.6487664440796, 18305.81694183068, 18617.162227699788, 18698.910762466057, 18877.81122188788, 18916.243350265835, 1027.7173777211326, 1028.280203857303, 1036.0623635609786, 1036.160709825044, 1042.4579218806177, 10501.474926253657, 10634.308769930085, 10734.409607471664, 10763.0, 10973.396463194442, 486275.0, 498453.0, 505075.0, 507411.0, 513780.0, 113854.0, 114558.0, 114988.0, 116453.835461646, 116517.41741291409, 1139.789139009286, 1176.8705442401329, 1249.9750004999848, 1259.1123258103198, 1272.4910035985495, 20122.59754804925, 20144.0, 20297.594048119252, 20772.272970445963, 20784.1686332545, 56330.43669563252, 57822.710886446985, 58947.41052589591, 59052.40947590469, 59917.0, 74753.0, 75066.62466687632, 75963.24036759562, 76480.0, 76565.23434765582, 14473.0, 14511.854881451472, 14529.27353632293, 14545.636359091108, 14570.198639074953, 22272.88863555672, 22436.551268974854, 22514.887425562767, 22585.887070564542, 22907.54184916259, 17678.46964591064, 17782.822171778116, 18173.54566135857, 18175.272989080288, 18183.636327273114, 16000.119993400474, 16133.596660083122, 16238.918805405898, 16370.590735231715, 16421.753673694664, 17679.73480397769, 18549.629007420044, 18701.719474207624, 18820.52948676294, 18831.717524236872, 14330.49843255481, 14499.275036248357, 14509.201993890438, 14521.0, 14523.927380363031, 15771.842281577037, 16662.83337166613, 16846.410375636795, 16849.663006739553, 17008.659826803643], "value": 11961.004983974959, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1276.253391765817, 1323.8146659467677, 1329.7633201122958, 1354.4311389216527, 1361.0948719101798, 15204.163770992605, 15830.287637056332, 15892.364305427769, 15951.760723589156, 15972.281247343868, 15254.771178432306, 15289.388424463008, 15352.385904563833, 15423.845761542381, 15455.149966751824, 2797.678266999291, 2825.717428257168, 2864.4127953769425, 2888.407876385336, 2895.565665150223, 2713.81003329767, 2738.780897528203, 2763.9309017274527, 2769.556870900656, 2773.209635253953, 2681.1554360376417, 2746.052611848909, 2761.0, 2875.741183293503, 2885.697001814811, 2825.4631619992238, 2859.1565488181036, 2859.2137162280333, 2865.054532004444, 2866.498362786504, 2682.973170268292, 2747.162115554775, 2747.32690490829, 2759.448110377912, 2768.8754006069576, 14518.201498917555, 14852.0, 14889.404423823034, 14969.925150374287, 14996.925015374962, 8217.753467395987, 8672.392932494728, 8726.563671816384, 8736.039035706068, 8743.562821858945, 5743.0, 5878.294604647427, 5902.822915312548, 5975.342712301644, 5996.5202783777195, 1994.6908229224493, 2007.9196832126843, 2016.6773316269328, 2019.3538067818304, 2068.338131797825, 27208.319792005357, 27512.862435687694, 27536.17391478259, 27562.448751024865, 27601.0, 6961.338672826175, 7675.53946763196, 7867.449278550561, 7907.644156012993, 8037.115917249098, 17002.149892505324, 17010.48968530946, 17115.400960966308, 17172.055536945587, 17206.0, 8028.598570071473, 8092.757217283491, 8544.401891867634, 8863.335249856158, 8872.911270887338, 7676.0, 7745.690172393041, 7770.184130666312, 7878.960605196938, 7883.408744344197, 5296.311479507716, 5432.402435732066, 5462.481064298885, 5466.781328746885, 5470.890582188334, 1674.7655328254054, 1677.5302915183768, 1698.3291599818003, 1700.753390758358, 1709.640975395181, 852.3564708644933, 856.4304737349593, 867.5011868175824, 870.4472659861034, 879.9956000220086, 7260.297896759, 7387.335139837333, 7488.625568721543, 7549.773506794805, 7554.508956917783, 714.0717067811823, 720.4704542161508, 726.7565365602477, 734.7612026091434, 739.0281779459993, 5096.261042148942, 5196.142636465017, 5242.108841496878, 5327.786888524415, 5395.244665746799, 21223.15107395687, 21384.144634214455, 21707.02318395676, 21727.565448691254, 21764.673529897063, 20648.277310294066, 21390.358289251348, 21419.46451338729, 21696.674549881765, 22023.66964495534, 11215.71960700989, 11379.374134422686, 11380.146489013357, 11543.480543375568, 11608.709782255512, 7968.0, 8209.876851847346, 8231.19610348121, 8247.21651443112, 8279.58602069882, 17971.460856174337, 18452.723209152144, 18789.718154227972, 18968.905155474687, 19143.329983450512, 18384.264629415207, 19196.328128515433, 19271.90364048171, 19455.221791128195, 19634.410967670992, 11217.326960382403, 11314.660560183207, 11324.0, 11327.207095503236, 11353.432328383382, 13674.453021879013, 13997.790033149307, 14071.859281407054, 14080.084794488326, 14091.154530728189, 11320.773584528428, 11521.711957201138, 11611.941940290244, 11632.069434445393, 12009.519619215134, 11059.281146725438, 11107.38909359993, 11217.439128043728, 11346.773064538827, 11465.598704045651, 10193.541290642088, 10320.225983051452, 10365.118964888068, 10381.11760500366, 10415.479226038819, 7232.63836808147, 7243.384312333511, 7331.450141239535, 7392.667329970057, 7474.962625186839, 7075.752348667772, 7146.606936618536, 7168.247334029852, 7232.5660460370445, 7307.050083489113], "value": 6769.43440693189, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.87801988512797, 507.3708601334345, 530.2651820560636, 535.0369335196646, 536.1261144334734, 6269.686515674215, 6275.68621568922, 6288.465480434163, 6313.305536390993, 6370.458511026557, 6161.630302181861, 6163.845903852406, 6254.655993920333, 6284.340144284842, 6286.685665716719, 5120.411152717438, 5145.253938178962, 5147.794088236466, 5160.225966105096, 5161.896762064756, 5043.84868453947, 5060.924086138711, 5064.620153488485, 5093.923591146136, 5102.36220472441, 4727.834525791598, 4812.181929072066, 4875.512448755114, 4915.557599816016, 4954.430240522332, 4875.219964805614, 4888.584470320027, 4889.533140057986, 4931.556159945586, 4965.981973695401, 4318.762468064287, 4382.737035777864, 4387.210302145612, 4397.890052748675, 4402.8679139625865, 5857.297124345063, 5923.200367950358, 5983.970080149571, 6073.696315184223, 6089.817305480843, 5542.7782888684405, 5546.944530554683, 5593.244911936918, 5595.048841696921, 5640.0, 6377.617342959437, 6409.276057675516, 6435.487660399803, 6481.384268494486, 6484.513661475408, 4566.40636717228, 4774.3077253798, 4798.904021919577, 4812.411904071648, 4838.395200599925, 6544.738210471575, 6566.212054553484, 6644.534882558223, 6665.366790154928, 6775.491838112211, 5230.032443997872, 5295.7087360195555, 5336.0, 5340.679559226382, 5395.433479484676, 5416.485433883775, 5451.890962180734, 5463.371712253142, 5467.0, 5469.453054694578, 5756.942430575725, 5817.272840894888, 5834.504067154255, 5856.1508581255475, 5860.860785813147, 6471.0, 6515.413612774873, 6540.215174179034, 6545.312742162099, 6585.473162147016, 1878.164216923456, 1880.4546681462223, 1903.181631898297, 1905.7236700678325, 1911.1113332300547, 1375.21612680771, 1428.271581493425, 1436.1885534672876, 1436.8491308412674, 1449.8187726534184, 5512.751926163332, 5543.445655434505, 5569.916451253234, 5587.161925711176, 5670.121131224667, 6430.839229019312, 6451.903221451682, 6464.450521705614, 6472.0, 6498.870022599616, 5408.350997880201, 5532.585056120807, 5537.972310138423, 5582.330120385498, 5615.213870058195, 3434.866494056955, 3439.174598096489, 3481.1993241554583, 3491.2493813830147, 3506.7545271830727, 6337.904931426032, 6390.360963903572, 6432.388923052396, 6438.323975982548, 6446.838829029218, 6641.86716265672, 6672.866542669217, 6734.292899245509, 6759.5944243344575, 6793.660316984131, 6223.216693803775, 6233.501319894488, 6312.715927783261, 6333.36666333363, 6386.010168423902, 6411.166548348685, 6503.414692677682, 6541.247756508063, 6588.670566471561, 6619.801405957829, 6518.478521718346, 6519.511036672364, 6522.380373864476, 6529.216494020748, 6544.280129185881, 6181.443670069535, 6188.319284878569, 6194.349593292639, 6277.323394071446, 6354.714037868214, 5292.894142117213, 5305.12465443213, 5390.19147127934, 5402.324709411324, 5425.321834770653, 6282.277538083179, 6516.478681705357, 6569.9014514783275, 6611.570247933869, 6635.2037755469655, 6258.499320054294, 6295.213098362705, 6297.716602752978, 6365.61806291624, 6413.551051426355, 6366.140571022853, 6388.977763557808, 6397.616143031433, 6419.133416988647, 6428.614283143026, 6249.281332646622, 6358.936410635834, 6383.170187875547, 6384.521160912857, 6408.102865598913, 6407.9038814418755, 6432.163818703539, 6505.642189679424, 6530.0, 6551.082848401323, 6222.81331560054, 6302.779402720883, 6313.936860631335, 6315.463185629273, 6330.683465826783], "value": 5050.05190510693, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [900.8198360327937, 906.1844340093915, 924.0574613893824, 927.1748144151702, 934.7102398256538, 19760.802391976093, 20584.279550215728, 20763.68854467184, 20770.169193232254, 20797.896010519966, 18500.0749962502, 18752.343667971625, 19105.14026868791, 19338.323158689447, 19444.51388715283, 947.8862536495631, 974.2741657465162, 976.6337623391229, 977.2524019125375, 981.1071924548662, 925.051821882569, 966.5505539923934, 967.6806653804227, 970.180197732918, 971.5190980464645, 986.8322385194534, 1020.3571749797617, 1024.87701475823, 1027.8201314769922, 1028.4240825137947, 1004.5931397783909, 1009.0766948242386, 1015.532854886753, 1016.6594190946042, 1020.3265844542603, 732.5677850068486, 739.7078154129089, 741.3068780690019, 741.907261592301, 742.4283301857594, 19193.136308866135, 20433.080511377022, 20748.170073197052, 20789.792102078936, 20798.896005519877, 21424.57150856974, 21505.784942150534, 21962.121515139374, 21963.450913727123, 22000.88999555008, 17259.395921142826, 17401.91299043497, 17455.127243637893, 17466.388676396266, 17479.563010924703, 3653.6163702811086, 3659.0669379308106, 3664.8900532984053, 3665.7067434605433, 3690.3910854708943, 141049.0, 141060.0, 143367.0, 144315.55684443127, 144890.0, 16743.24655390486, 16935.830641693672, 16943.491695249162, 17058.914705426392, 17119.91440042792, 31334.0, 31581.210469738442, 31878.681213188036, 31963.520547191813, 32157.0, 8241.629126689193, 8347.332213422911, 8358.080611132771, 8374.53940033304, 8504.0, 4192.601702838226, 4263.467066616673, 4266.509351424564, 4267.103908179255, 4273.230818452708, 5573.972130139324, 5628.409017053233, 5641.746121424546, 5645.633033852869, 5717.742701578439, 1056.1920131099714, 1065.4938904020528, 1074.570171931218, 1078.654830454247, 1079.1582565598846, 694.6943344928216, 696.0498918975663, 703.3669697272496, 703.7642389799474, 706.4313227851629, 11840.526378944918, 11905.0, 11974.22167559106, 12929.54746583884, 13105.475780968654, 664.6045602866287, 668.3316683316683, 687.8761822871832, 693.2478261086677, 703.0684343245221, 5342.171963345688, 5472.288602481652, 5498.972505137449, 5548.0845660466375, 5598.076317407664, 116539.41730291295, 119582.0, 122544.38727806482, 122579.38710306391, 124225.37887310686, 107976.0, 108476.4576177114, 109762.0, 109933.45033274783, 110142.0, 94172.0, 97957.99999999857, 98083.50958245163, 98427.0, 99050.0, 10209.285350025724, 10319.690409287734, 10366.637167699095, 10378.896211037792, 10482.895171048192, 44035.0, 44143.11713765771, 44384.0, 44549.55450445454, 45306.77346613246, 53466.465335347704, 55068.724656376464, 56264.15603765865, 56413.71793141008, 57707.4229257702, 15174.013689110172, 15609.375624974873, 15717.135557543987, 15793.763093553376, 15905.0, 16966.915165424092, 17089.401870934456, 17344.47966561005, 17543.0, 17742.645147097242, 15442.45951391696, 15724.76412853831, 15858.207089645703, 16124.0, 16203.75694364562, 14750.63123421923, 14806.62983425423, 14870.479533216285, 14909.776353354926, 15001.399944002555, 14097.788533172215, 14341.713165736419, 14423.78364324557, 14558.272086395426, 14574.271286435847, 11630.94184529072, 11661.591844285409, 11884.0, 11898.524059037542, 12124.333161676192, 11701.414929253673, 11729.589464368706, 11823.46794394237, 11902.583409580624, 12066.155369124077], "value": 8927.1655666485, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 110.0, 108.0, 115.0, 106.0, 108.0, 114.0, 107.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 129.0, 120.0, 125.0, 120.0, 150.0, 134.0, 120.0, 119.0, 120.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 172.0, 172.0, 164.0, 170.0, 170.0, 162.0, 163.0, 164.0], "value": 167.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 81.0, 87.0, 80.0, 77.0, 73.0, 82.0, 75.0, 76.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 88.0, 84.0, 79.0, 85.0, 79.0, 88.0, 80.0, 80.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 118.0, 116.0, 120.0, 116.0, 114.0, 116.0, 117.0, 114.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 158.0, 151.0, 152.0, 149.0, 148.0, 148.0, 149.0, 158.0, 156.0], "value": 152.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 99.0, 101.0, 95.0, 94.0, 94.0, 99.0, 95.0, 94.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 50.0, 50.0, 53.0, 61.0, 55.0, 53.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 49.0, 42.0, 38.0, 40.0, 39.0, 42.0, 38.0, 44.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 81.0, 78.0, 77.0, 76.0, 76.0, 75.0, 76.0, 75.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 124.0, 116.0, 117.0, 111.0, 117.0, 109.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 176.0, 167.0, 172.0, 167.0, 167.0, 168.0, 175.0, 176.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 70.0, 66.0, 69.0, 68.0, 68.0, 65.0, 67.0], "value": 66.9, "unit": "score"}]}]} 18:50:00 INFO - Return code: 0 18:50:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:01 INFO - # TBPL SUCCESS # 18:50:01 INFO - Running post-action listener: _package_coverage_data 18:50:01 INFO - Running post-action listener: _resource_record_post_action 18:50:01 INFO - [mozharness: 2017-09-21 01:50:01.011578Z] Finished run-tests step (success) 18:50:01 INFO - Running post-run listener: _resource_record_post_run 18:50:01 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 19578880; Write bytes: 491364352; Read time: 7184; Write time: 345608 18:50:01 INFO - TinderboxPrint: CPU usage
13.3% 18:50:01 INFO - TinderboxPrint: I/O read bytes / time
19,578,880 / 7,184 18:50:01 INFO - TinderboxPrint: I/O write bytes / time
491,364,352 / 345,608 18:50:01 INFO - TinderboxPrint: CPU idle
8,265.6 (86.7%) 18:50:01 INFO - TinderboxPrint: CPU user
1,201.5 (12.6%) 18:50:01 INFO - TinderboxPrint: Swap in / out
0 / 0 18:50:01 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 144384000; Read time: 92; Write time: 131544 18:50:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:50:01 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 17203200; Write bytes: 346980352; Read time: 6608; Write time: 214064 18:50:01 INFO - Running post-run listener: _upload_blobber_files 18:50:01 INFO - Blob upload gear active. 18:50:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:01 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 18:50:01 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'] 18:50:01 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 18:50:02 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:03 INFO - (blobuploader) - INFO - Done attempting. 18:50:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:09 INFO - (blobuploader) - INFO - Done attempting. 18:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:10 INFO - (blobuploader) - INFO - Done attempting. 18:50:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:50:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:11 INFO - (blobuploader) - INFO - Done attempting. 18:50:11 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:11 INFO - Return code: 0 18:50:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bdfe8530eff77a123246e73cc25f43658f1b103d86560efff7a4fdbba05a2cbbd831d0b0e96b3e0ee6f0eac7d9052eb2d84b1a70fbe7672e378beea5d34506f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4de30dd8a455b457d44760c04ed8ff2beb6c7d55cb62f6390cf87af11333d4c1520fe6fc66fa31991d6008675e89b218cf99c32c842d97579468e575f8859e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c322b107c08835a2a842b65a096b56f13c1a06c4e39a92efa16973abe407fa12b8945f1c361e937a8e11b1f4d4d5f938b5232a9b068fdfd8a4a4ccdefb01b47", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db88047980843d3acd1437539c43b28f1937907902817e420019f34cba7cff061648dac10118819bdc2954c14f3253d9c572cff078fe0dd62d0fad832d2d51"} 18:50:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:11 INFO - Contents: 18:50:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bdfe8530eff77a123246e73cc25f43658f1b103d86560efff7a4fdbba05a2cbbd831d0b0e96b3e0ee6f0eac7d9052eb2d84b1a70fbe7672e378beea5d34506f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4de30dd8a455b457d44760c04ed8ff2beb6c7d55cb62f6390cf87af11333d4c1520fe6fc66fa31991d6008675e89b218cf99c32c842d97579468e575f8859e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c322b107c08835a2a842b65a096b56f13c1a06c4e39a92efa16973abe407fa12b8945f1c361e937a8e11b1f4d4d5f938b5232a9b068fdfd8a4a4ccdefb01b47", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db88047980843d3acd1437539c43b28f1937907902817e420019f34cba7cff061648dac10118819bdc2954c14f3253d9c572cff078fe0dd62d0fad832d2d51"} 18:50:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:11 INFO - Copying logs to upload dir... 18:50:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.676637 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-20 18:50:11.333974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:50:11.341272) ========= 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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bdfe8530eff77a123246e73cc25f43658f1b103d86560efff7a4fdbba05a2cbbd831d0b0e96b3e0ee6f0eac7d9052eb2d84b1a70fbe7672e378beea5d34506f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4de30dd8a455b457d44760c04ed8ff2beb6c7d55cb62f6390cf87af11333d4c1520fe6fc66fa31991d6008675e89b218cf99c32c842d97579468e575f8859e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c322b107c08835a2a842b65a096b56f13c1a06c4e39a92efa16973abe407fa12b8945f1c361e937a8e11b1f4d4d5f938b5232a9b068fdfd8a4a4ccdefb01b47", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db88047980843d3acd1437539c43b28f1937907902817e420019f34cba7cff061648dac10118819bdc2954c14f3253d9c572cff078fe0dd62d0fad832d2d51"} build_url:https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/YpEoj-OJRvCSQ0IhvHvM7g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bdfe8530eff77a123246e73cc25f43658f1b103d86560efff7a4fdbba05a2cbbd831d0b0e96b3e0ee6f0eac7d9052eb2d84b1a70fbe7672e378beea5d34506f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4de30dd8a455b457d44760c04ed8ff2beb6c7d55cb62f6390cf87af11333d4c1520fe6fc66fa31991d6008675e89b218cf99c32c842d97579468e575f8859e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c322b107c08835a2a842b65a096b56f13c1a06c4e39a92efa16973abe407fa12b8945f1c361e937a8e11b1f4d4d5f938b5232a9b068fdfd8a4a4ccdefb01b47", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db88047980843d3acd1437539c43b28f1937907902817e420019f34cba7cff061648dac10118819bdc2954c14f3253d9c572cff078fe0dd62d0fad832d2d51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:50:11.376790) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:50:11.377143) ========= 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=ed14279e38b05397cf4aa391000001a4-1505957361.865107-935261101 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:50:11.461848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:50:11.462422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:50:11.463005) ========= ========= Total master_lag: 0.28 =========