Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16291688 kB
MemAvailable 31033436 kB
Buffers 0 kB
Cached 15407328 kB
SwapCached 2632 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-12 19:37:46
DTS_END                         2021-08-12 19:37:46.1540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42088
TRN_ID                          245147
ATT_ID                          641
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9233.13 20390 1107976
customer order: creation 177.63 347 21316
customer order: refuse 15.51 332 1861
order to supplier: creation 47.44 1258 5693
order to supplier: removal 3.43 96851 412
invoice (draft): creation 48.16 3391 5779
invoice (draft): removal 50.47 804 6056
invoice accept: apply 43.07 16298 5168
invoice accept: cancel 44.93 778 5392
customer reserve: creation 4025.10 159 483012
customer reserve: removal 4483.37 58 538004
realization accept: apply 86.83 188 10419
realization accept: cancel 72.23 112 8668
payment from customer: creation 61.14 135 7337
payment from customer: removal 5.85 111 702
payment to supplier: creation 51.90 124 6228
payment to supplier: removal 3.82 54 458
service: total inventory turnovers 4.70 1405 564
service: total monetary turnovers 6.55 21 786
service: aggregate perf. data 0.98 17066 117
service: refresh index statistics 0.03 268307 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2021 19:39:42 to 12-08-2021 19:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4883
TEST_TIME 2 3964
TEST_TIME 3 4326
TEST_TIME 4 5216
TEST_TIME 5 4761
TEST_TIME 6 5007
TEST_TIME 7 5146
TEST_TIME 8 5222
TEST_TIME 9 5265
TEST_TIME 10 5418
TEST_TIME 11 5513
TEST_TIME 12 5596
TEST_TIME 13 5860
TEST_TIME 14 6380
TEST_TIME 15 6457
TEST_TIME 16 6992
TEST_TIME 17 7377
TEST_TIME 18 7526
TEST_TIME 19 8044
TEST_TIME 20 8130
TEST_TIME 21 8492
TEST_TIME 22 8670
TEST_TIME 23 8819
TEST_TIME 24 9235
TEST_TIME 25 9467
TEST_TIME 26 9844
TEST_TIME 27 10243
TEST_TIME 28 10744
TEST_TIME 29 10591
TEST_TIME 30 10376
TEST_TIME 31 10439
TEST_TIME 32 10373
TEST_TIME 33 10555
TEST_TIME 34 10572
TEST_TIME 35 10583
TEST_TIME 36 10440
TEST_TIME 37 10189
TEST_TIME 38 10083
TEST_TIME 39 9943
TEST_TIME 40 9854
TEST_TIME 41 9839
TEST_TIME 42 9899
TEST_TIME 43 9903
TEST_TIME 44 9877
TEST_TIME 45 9833
TEST_TIME 46 9763
TEST_TIME 47 9656
TEST_TIME 48 9574
TEST_TIME 49 9573
TEST_TIME 50 9581
TEST_TIME 51 9604
TEST_TIME 52 9589
TEST_TIME 53 9605
TEST_TIME 54 9568
TEST_TIME 55 9517
TEST_TIME 56 9503
TEST_TIME 57 9711
TEST_TIME 58 9724
TEST_TIME 59 9723
TEST_TIME 60 9725
TEST_TIME 61 9701
TEST_TIME 62 9900
TEST_TIME 63 9928
TEST_TIME 64 9917
TEST_TIME 65 10079
TEST_TIME 66 9981
TEST_TIME 67 9873
TEST_TIME 68 9837
TEST_TIME 69 9804
TEST_TIME 70 9782
TEST_TIME 71 9776
TEST_TIME 72 9706
TEST_TIME 73 9673
TEST_TIME 74 9630
TEST_TIME 75 9599
TEST_TIME 76 9511
TEST_TIME 77 9589
TEST_TIME 78 9551
TEST_TIME 79 9518
TEST_TIME 80 9535
TEST_TIME 81 9534
TEST_TIME 82 9524
TEST_TIME 83 9563
TEST_TIME 84 9620
TEST_TIME 85 9574
TEST_TIME 86 9591
TEST_TIME 87 9591
TEST_TIME 88 9634
TEST_TIME 89 9646
TEST_TIME 90 9672
TEST_TIME 91 9651
TEST_TIME 92 9564
TEST_TIME 93 9532
TEST_TIME 94 9516
TEST_TIME 95 9511
TEST_TIME 96 9440
TEST_TIME 97 9413
TEST_TIME 98 9372
TEST_TIME 99 9397
TEST_TIME 100 9388
TEST_TIME 101 9402
TEST_TIME 102 9395
TEST_TIME 103 9377
TEST_TIME 104 9388
TEST_TIME 105 9420
TEST_TIME 106 9445
TEST_TIME 107 9417
TEST_TIME 108 9374
TEST_TIME 109 9440
TEST_TIME 110 9426
TEST_TIME 111 9446
TEST_TIME 112 9500
TEST_TIME 113 9442
TEST_TIME 114 9399
TEST_TIME 115 9362
TEST_TIME 116 9349
TEST_TIME 117 9301
TEST_TIME 118 9302
TEST_TIME 119 9267
TEST_TIME 120 9234

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2021 19:39:46 to 12-08-2021 19:39:49.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1553909 1553909 0 0.00 0 26495 6 0 0
doc_list_biud 1553908 1553908 0 0.00 0 48618 26 0 0
sp_add_invoice_to_stock 5234 5168 66 1.26 29 140067 16299 0 66
sp_cancel_adding_invoice 5392 5392 0 0.00 4 48730 779 0 0
sp_cancel_client_order 1861 1861 0 0.00 4 18170 332 0 0
sp_cancel_customer_reserve 538004 538004 0 0.00 1 48620 59 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 9894 111 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 3 1058 54 0 0
sp_cancel_supplier_invoice 6060 6056 4 0.06 2 28417 804 0 4
sp_cancel_supplier_order 412 412 0 0.00 5 954304 96852 0 0
sp_cancel_write_off 8668 8668 0 0.00 1 15775 113 0 0
sp_client_order 21316 21316 0 0.00 3 47848 347 0 0
sp_customer_reserve 483012 483012 0 0.00 0 108203 159 0 0
sp_fill_shopping_cart 32773 32773 0 0.00 1 9864 38 0 0
sp_fill_shopping_cart_clo_res 483009 483009 0 0.00 0 14595 12 0 0
sp_get_clo_for_invoice 5162 5162 0 0.00 0 24403 299 0 0
sp_kill_cost_storno 15220 15220 0 0.00 0 26482 57 0 0
sp_kill_qstorno_ret_qs2qd 1091144 1091144 0 0.00 0 48616 32 0 0
sp_kill_qty_storno 575964 575964 0 0.00 0 48618 70 0 0
sp_lock_selected_doc 21622 21622 0 0.00 0 10550 5 0 0
sp_make_cost_storno 29136 29136 0 0.00 0 14956 60 0 0
sp_multiply_rows_for_pdistr 29135 29135 0 0.00 0 10514 33 0 0
sp_multiply_rows_for_qdistr 21316 21316 0 0.00 0 47808 154 0 0
sp_pay_from_customer 7337 7337 0 0.00 4 14848 136 0 0
sp_pay_to_supplier 6228 6228 0 0.00 5 15558 124 0 0
sp_payment_common 13564 13564 0 0.00 1 12470 41 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 17653 314 0 0
sp_qd_handle_on_invoice_upd_sts 10544 10544 0 0.00 0 26830 342 0 0
sp_qd_handle_on_reserve_upd_sts 10419 10419 0 0.00 0 20912 74 0 0
sp_reserve_write_off 10419 10419 0 0.00 6 20929 189 0 0
sp_supplier_invoice 5779 5779 0 0.00 7 76440 3392 0 0
sp_supplier_order 5693 5693 0 0.00 5 32080 1258 0 0
srv_aggregate_perf_data 117 117 0 0.00 2660 180283 17067 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1760 152805 48721 0 0
srv_find_qd_qs_mism 1057579 1057579 0 0.00 0 17987 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 46 172356 1405 0 0
srv_make_money_saldo 786 786 0 0.00 1 1567 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 139427 473690 268308 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5168 5168 0 0.00 0 19453 169 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 3664 73 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 11674 19 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 4246 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 882 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 4048 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 11058 50 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 19284 1575 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 8294 19 0 0
t$perf-norm:sp_client_order 21316 21316 0 0.00 0 9080 17 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 5192 12 0 0
t$perf-norm:sp_pay_from_customer 7337 7337 0 0.00 0 7236 13 0 0
t$perf-norm:sp_pay_to_supplier 6228 6228 0 0.00 0 12331 16 0 0
t$perf-norm:sp_reserve_write_off 10419 10419 0 0.00 0 9064 14 0 0
t$perf-norm:sp_supplier_invoice 5779 5779 0 0.00 0 7892 22 0 0
t$perf-norm:sp_supplier_order 5693 5693 0 0.00 0 12303 19 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 289 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 832 19 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 2267 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10436 10304 132 1.26 0 6295 16 0 132
v_all_customers 38556 38556 0 0.00 0 19 1 0 0
v_all_suppliers 32106 32106 0 0.00 0 17 1 0 0
v_all_wares 42628 42628 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 3311 11 0 0
v_cancel_client_order 3722 3722 0 0.00 0 2747 5 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 2943 9 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 492 3 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 510 4 0 0
v_cancel_supplier_invoice 876 868 8 0.91 0 469 12 0 8
v_cancel_supplier_order 824 824 0 0.00 0 2510 6 0 0
v_cancel_write_off 2078 2078 0 0.00 0 5141 17 0 0
v_max_id_clo_ord 5692 5692 0 0.00 0 394 1 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 4361 19 0 0
v_max_id_ord_sup 5767 5767 0 0.00 0 5707 4 0 0
v_max_non_paid_invoice 1633 1633 0 0.00 0 4864 8 0 0
v_max_non_paid_realizn 4042 4042 0 0.00 0 3002 4 0 0
v_min_id_clo_ord 5692 5692 0 0.00 0 1915 5 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 8928 21 0 0
v_min_id_ord_sup 5767 5767 0 0.00 0 5033 7 0 0
v_min_non_paid_invoice 6227 6227 0 0.00 0 8371 22 0 0
v_min_non_paid_realizn 7337 7337 0 0.00 0 11032 33 0 0
v_our_firm 10662 10662 0 0.00 0 14 1 0 0
v_reserve_write_off 20838 20838 0 0.00 0 7912 5 0 0
x_lock_depdocs_on_canc_invoice 11014 11014 0 0.00 0 39693 117 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5108 294 0 0
x_make_qstorno_1000_1200 5693 5693 0 0.00 2 32074 1184 0 0
x_make_qstorno_1200_2000 5779 5779 0 0.00 4 72586 3246 0 0
x_make_qstorno_2100_3300 483012 483012 0 0.00 0 108202 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2021 19:39:50 to 12-08-2021 19:39:54.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:50 35485 8192000000 10365948576 10684477440 1419347776 14.767 100 95 191 2 1457439888 6377568 31714544
17:10:28 22306 8192000000 10439598208 10771111936 1478741328 15.290 100 88 176 0 1516048896 8306976 29000592
17:11:54 13457 8192000000 10466218512 10810003456 1528525248 15.724 100 82 164 0 1564197952 9542976 26129728
17:13:10 11281 8192000000 10550913808 10907471872 1603063168 16.366 100 76 152 0 1636996912 10684544 23249200
17:14:25 7131 8192000000 10624297536 10990702592 1685401952 17.063 100 85 170 0 1722372672 12812080 24158640
17:15:34 3638 8192000000 10680995920 11057098752 1742895328 17.543 100 77 151 1 1776037264 11907040 21234896
17:16:39 6080 8192000000 10740163008 11122724864 1788375280 17.918 100 83 166 0 1825435184 14488576 22571328
17:17:47 2659 8192000000 10775876064 11163131904 1836020464 18.308 100 88 176 0 1874120912 13449120 24651328
17:18:51 1871 8192000000 10812445360 11205943296 1870276160 18.587 100 88 178 1 1908408480 12719904 25412416
17:19:55 19432 8192000000 10803753424 11203850240 1869129840 18.577 100 38 75 0 1887101312 7376016 10595456
17:21:16 6283 8192000000 10784332368 11211530240 1877243664 18.643 100 24 48 3 1890890736 6683152 6963920
17:22:23 5462 8192000000 10801667696 11229745152 1893374384 18.773 100 24 49 1 1906283296 5804928 7103984
17:23:33 58372 8192000000 10825017728 11252760576 1903282176 18.853 100 42 84 3 1921846304 6257856 12306272
17:25:37 8220 8192000000 10825720032 11261595648 1907141040 18.884 100 18 35 0 1917668704 5536400 4991264
17:26:46 5563 8192000000 10826887840 11266314240 1915533152 18.951 100 29 58 1 1931014688 6949440 8532096
17:27:53 10654 8192000000 10812531472 11250360320 1893205168 18.772 99 24 48 0 1906170048 5170592 7794288
17:29:04 26910 8192000000 10769699952 11202019328 1768383312 17.754 93 19 38 2 1778604848 5009184 5212352
17:30:31 7645 8192000000 10682744640 11099308032 1670025120 16.933 91 32 63 0 1684239168 6180848 8033200
17:31:44 5895 8192000000 10722134320 11131674624 1782265760 17.868 99 23 46 0 1794594368 5648304 6680304
17:32:51 68617 8192000000 10734974992 11158654976 1792361248 17.951 98 34 68 1 1808291776 5268448 10662080
17:35:02 4243 8192000000 10766166832 11193942016 1855725424 18.469 100 34 64 3 1871265696 6429456 9110816
17:36:07 8450 8192000000 10774716576 11208310784 1863489376 18.532 100 12 23 1 1871841376 5065984 3286016
17:37:16 15161 8192000000 10805665664 11237859328 1894847984 18.785 100 9 17 1 1901611648 4629504 2134160
17:38:46 44513 8192000000 10864205712 11287465984 1926658720 19.040 100 36 70 3 1943183776 6374624 10150432
17:40:53 16007 8192000000 10900167056 11325620224 1958396128 19.293 100 47 93 0 1985548144 13565504 13586512
17:42:13 39816 8192000000 10917204288 11356721152 1979366944 19.460 100 29 58 0 2001588032 13620192 8600896
17:43:58 11421 8192000000 10939846672 11369955328 1992231184 19.561 100 58 116 1 2028990656 19867440 16892032
17:45:16 6693 8192000000 10949540992 11383123968 2007682624 19.683 100 62 123 2 2047249456 21282848 18283984
17:46:27 6498 8192000000 10965614384 11394834432 2010972784 19.709 100 51 102 1 2055140736 28766448 15401504
17:47:39 17283 8192000000 10967710864 11407400960 2010270640 19.704 100 46 88 0 2050949024 26814688 13863696
17:49:00 15914 8192000000 10980042192 11422605312 2020020736 19.780 100 40 80 0 2067320832 34095232 13204864
17:50:22 11496 8192000000 10981572240 11423195136 2011861488 19.716 100 58 116 1 2074340160 43660944 18817728
17:51:38 15943 8192000000 10981641200 11418447872 2017125888 19.758 100 57 112 1 2075919152 41498208 17295056
17:53:03 3690 8192000000 11013632512 11436290048 2019182768 19.774 100 80 159 1 2090403888 47337072 23884048
17:54:13 6712 8192000000 10956197696 11388895232 1958249360 19.292 99 54 104 0 2021774128 47010080 16514688
17:55:23 1759 8192000000 10967850480 11381538816 1978771008 19.455 100 84 168 2 2046137040 41764320 25601712
17:56:28 6534 8192000000 10907813488 11327107072 1922156960 19.004 98 63 124 3 1983322656 41680144 19485552
17:57:45 8979 8192000000 10945162048 11378458624 1958603888 19.295 99 42 84 2 2011250688 39250160 13396640
17:59:00 6308 8192000000 10916827392 11345002496 1931724464 19.081 99 43 86 1 1982965584 37351888 13889232
18:00:08 19633 8192000000 10862717488 11301265408 1859406256 18.498 96 32 64 3 1907761488 37916816 10438416
18:01:31 15457 8192000000 10900852384 11339902976 1913054800 18.931 99 30 60 0 1967647024 44251712 10340512
18:02:51 17602 8192000000 10886153872 11336720384 1915012496 18.947 99 29 56 4 1975073952 49637872 10423584
18:04:09 54041 8192000000 10868519792 11311067136 1888410400 18.733 98 28 56 1 1940683920 43095904 9177616
18:06:05 25092 8192000000 10869561808 11293188096 1872826112 18.607 97 28 56 4 1890174432 9898704 7449616
18:07:47 64047 8192000000 10880210192 11334762496 1916939904 18.962 98 31 62 0 1936899312 11712624 8246784
18:09:52 89130 8192000000 10884704816 11349254144 1919123568 18.980 98 8 16 0 1937315888 16473072 1719248
18:12:28 95330 8192000000 10896930944 11359547392 1948733808 19.216 99 14 28 0 1963123280 10600432 3789040
18:15:37 152804 8192000000 10933279984 11381166080 1963884352 19.337 99 36 72 1 1983001312 9459360 9657600
18:19:13 40564 8192000000 10917115104 11406741504 1992513664 19.564 100 12 24 0 2010910768 13913248 4483856
18:20:54 53014 8192000000 10929190848 11408039936 1994358176 19.578 100 12 24 1 2009241216 11528128 3354912
18:23:19 50042 8192000000 10948949488 11412406272 1984194192 19.498 99 11 22 0 2000869216 13531712 3143312
18:25:17 86332 8192000000 10952459440 11416813568 2007334416 19.681 100 16 32 0 2033513120 21168176 5010528
18:27:58 56239 8192000000 10934022960 11413491712 2003230080 19.648 100 8 16 0 2021522688 16075312 2217296
18:29:57 60778 8192000000 10921817008 11405787136 1969325104 19.380 98 15 30 2 1983476272 10127840 4023328
18:32:00 47115 8192000000 10955601168 11421773824 1995625552 19.588 99 27 54 1 2014974576 11822272 7526752
18:33:49 51935 8192000000 10927152752 11401621504 1948796608 19.217 97 21 42 3 1973389648 18060144 6532896
18:35:51 23749 8192000000 10928935568 11396046848 1977328752 19.444 99 21 42 2 1992557968 8953584 6275632
18:37:15 20134 8192000000 10929118032 11390177280 1948078576 19.211 97 28 56 0 1965697184 9178352 8440256
18:38:39 54822 8192000000 10910343504 11398725632 1928985104 19.059 97 10 20 1 1948228144 16624016 2619024
18:40:34 66263 8192000000 10875310880 11333148672 1853554016 18.451 95 29 57 1 1878732784 16838912 8339856
18:42:43 124654 8192000000 10858215744 11307388928 1775069840 17.809 91 21 42 0 1797823152 16355408 6397904
18:46:21 34581 8192000000 10842696992 11309592576 1906371952 18.878 98 34 68 0 1930391296 14607328 9412016
18:47:57 58196 8192000000 10836973824 11322818560 1840994096 18.349 95 17 34 2 1859344736 13705952 4644688
18:50:09 41221 8192000000 10833966112 11311984640 1875735984 18.631 98 12 24 0 1889229536 10421584 3071968
18:51:54 61254 8192000000 10856518064 11336331264 1926122224 19.036 100 16 32 1 1944683488 13753216 4808048
18:54:23 61994 8192000000 10821661824 11298414592 1870398528 18.587 97 15 28 1 1882252768 7974080 3880160
18:56:25 83652 8192000000 10854467888 11326877696 1885108016 18.706 98 17 34 0 1899800192 10282416 4409760
18:59:19 29519 8192000000 10881610928 11343609856 1928921040 19.058 99 31 62 0 1947774416 9897984 8955392
19:00:57 78464 8192000000 10885963104 11350507520 1948416768 19.214 100 19 38 0 1963666416 10228112 5021536
19:03:16 90749 8192000000 10908582720 11370094592 1967496208 19.366 100 24 47 3 1986018448 11342112 7180128
19:05:47 74346 8192000000 10921964496 11383873536 1977348976 19.444 100 14 27 0 1991035088 10296528 3389584
19:08:03 116592 8192000000 10936561344 11404611584 1988202848 19.530 99 22 44 2 2007683088 13266096 6214144
19:11:22 4730 8192000000 10941363168 11396288512 1987596544 19.525 99 52 104 0 2013063344 11042032 14424768
19:12:27 52706 8192000000 10905768736 11393433600 1991611904 19.557 100 28 56 0 2012434064 12399776 8422384
19:14:20 80154 8192000000 10938161648 11413344256 2003264032 19.648 100 16 32 2 2016054784 8109776 4680976
19:16:58 65493 8192000000 10962085072 11416588288 1997836928 19.606 99 23 46 0 2014788704 9829280 7122496
19:19:08 48663 8192000000 10939910784 11422408704 1993587968 19.572 99 23 46 2 2011742016 11238864 6915184
19:21:06 60412 8192000000 10935738016 11426267136 1996220528 19.593 99 20 40 0 2018877440 16927824 5729088
19:23:07 90308 8192000000 10902142176 11357270016 1849398000 18.417 93 21 42 1 1863885088 9077040 5410048
19:25:42 68313 8192000000 10854998288 11341299712 1870673136 18.590 96 14 28 0 1886287824 11241632 4373056
19:27:52 50950 8192000000 10882320208 11359223808 1920139520 18.988 98 23 46 0 1935508512 8619104 6749888
19:29:52 128579 8192000000 10857845616 11328262144 1886656368 18.719 97 20 40 0 1901437808 9488928 5292512
19:33:01 42731 8192000000 10872379424 11347050496 1935154240 19.108 99 11 22 0 1947685632 8765072 3766320
19:35:57 59989 8192000000 10852474400 11335090176 1907913152 18.890 98 13 26 0 1927282784 14912112 4457520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2021 19:39:56 to 12-08-2021 19:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2021 19:40:11 to 12-08-2021 19:40:13.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241864
MON$OLDEST_ACTIVE               245263
MON$OLDEST_SNAPSHOT             245263
MON$NEXT_TRANSACTION            245263
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2033632
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:167: 
:168: mscs-test-161.budzdorov.ru    Thu Aug 12 19:37:48 2021
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Thu Aug 12 19:38:07 2021
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Thu Aug 12 19:38:07 2021
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:178: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

12.08.21 19:40:14. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-12 19:40:17 No severe PSQL-related problems occured
12.08.21 19:40:18. End of report.