Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2022 16:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16457136 kB
MemAvailable 31248812 kB
Buffers 0 kB
Cached 15186120 kB
SwapCached 1204 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.9
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 2022-01-10 16:38:04
DTS_END                         2022-01-10 16:38:04.2760
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/44812
TRN_ID                          237413
ATT_ID                          629
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: 9236.92 25810 1108431
customer order: creation 164.23 333 19708
customer order: refuse 13.91 322 1669
order to supplier: creation 44.10 1217 5292
order to supplier: removal 3.58 110337 429
invoice (draft): creation 43.71 3585 5245
invoice (draft): removal 55.50 835 6660
invoice accept: apply 39.29 17224 4715
invoice accept: cancel 49.81 804 5977
customer reserve: creation 3642.89 169 437147
customer reserve: removal 4884.26 63 586111
realization accept: apply 80.36 192 9643
realization accept: cancel 89.38 109 10725
payment from customer: creation 57.97 156 6956
payment from customer: removal 5.24 91 629
payment to supplier: creation 47.28 177 5674
payment to supplier: removal 3.31 57 397
service: total inventory turnovers 4.98 1185 598
service: total monetary turnovers 6.20 27 744
service: aggregate perf. data 0.90 17643 108
service: refresh index statistics 0.03 361664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-01-2022 16:38:55 to 10-01-2022 16:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4813
TEST_TIME 2 3455
TEST_TIME 3 3683
TEST_TIME 4 3653
TEST_TIME 5 3798
TEST_TIME 6 4841
TEST_TIME 7 5137
TEST_TIME 8 5766
TEST_TIME 9 5897
TEST_TIME 10 5757
TEST_TIME 11 5875
TEST_TIME 12 5937
TEST_TIME 13 6155
TEST_TIME 14 6232
TEST_TIME 15 6257
TEST_TIME 16 6239
TEST_TIME 17 6662
TEST_TIME 18 6928
TEST_TIME 19 7247
TEST_TIME 20 7543
TEST_TIME 21 7864
TEST_TIME 22 8197
TEST_TIME 23 8113
TEST_TIME 24 9039
TEST_TIME 25 9100
TEST_TIME 26 9692
TEST_TIME 27 11028
TEST_TIME 28 10916
TEST_TIME 29 10657
TEST_TIME 30 10627
TEST_TIME 31 10716
TEST_TIME 32 10573
TEST_TIME 33 10435
TEST_TIME 34 10203
TEST_TIME 35 10033
TEST_TIME 36 9993
TEST_TIME 37 9926
TEST_TIME 38 9790
TEST_TIME 39 9818
TEST_TIME 40 9718
TEST_TIME 41 9594
TEST_TIME 42 9478
TEST_TIME 43 9331
TEST_TIME 44 9415
TEST_TIME 45 9382
TEST_TIME 46 9404
TEST_TIME 47 9272
TEST_TIME 48 9192
TEST_TIME 49 9154
TEST_TIME 50 9060
TEST_TIME 51 9076
TEST_TIME 52 8999
TEST_TIME 53 8864
TEST_TIME 54 8928
TEST_TIME 55 8870
TEST_TIME 56 8913
TEST_TIME 57 8959
TEST_TIME 58 8935
TEST_TIME 59 8996
TEST_TIME 60 9026
TEST_TIME 61 9055
TEST_TIME 62 9075
TEST_TIME 63 9103
TEST_TIME 64 9138
TEST_TIME 65 9230
TEST_TIME 66 9213
TEST_TIME 67 9261
TEST_TIME 68 9335
TEST_TIME 69 9337
TEST_TIME 70 9401
TEST_TIME 71 9338
TEST_TIME 72 9223
TEST_TIME 73 9219
TEST_TIME 74 9155
TEST_TIME 75 9180
TEST_TIME 76 9121
TEST_TIME 77 9096
TEST_TIME 78 9102
TEST_TIME 79 9056
TEST_TIME 80 8949
TEST_TIME 81 8927
TEST_TIME 82 8963
TEST_TIME 83 9004
TEST_TIME 84 8971
TEST_TIME 85 9017
TEST_TIME 86 9043
TEST_TIME 87 9100
TEST_TIME 88 9090
TEST_TIME 89 9054
TEST_TIME 90 9144
TEST_TIME 91 9162
TEST_TIME 92 9175
TEST_TIME 93 9335
TEST_TIME 94 9316
TEST_TIME 95 9325
TEST_TIME 96 9268
TEST_TIME 97 9233
TEST_TIME 98 9209
TEST_TIME 99 9207
TEST_TIME 100 9186
TEST_TIME 101 9160
TEST_TIME 102 9145
TEST_TIME 103 9161
TEST_TIME 104 9129
TEST_TIME 105 9057
TEST_TIME 106 9026
TEST_TIME 107 9084
TEST_TIME 108 9071
TEST_TIME 109 9046
TEST_TIME 110 9066
TEST_TIME 111 9142
TEST_TIME 112 9126
TEST_TIME 113 9177
TEST_TIME 114 9160
TEST_TIME 115 9153
TEST_TIME 116 9183
TEST_TIME 117 9207
TEST_TIME 118 9288
TEST_TIME 119 9312
TEST_TIME 120 9237

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2022 16:38:59 to 10-01-2022 16:39:02.

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 1514502 1514502 0 0.00 0 25291 6 0 0
doc_list_biud 1514499 1514499 0 0.00 0 22163 30 0 0
sp_add_invoice_to_stock 4831 4715 116 2.40 18 204661 17224 0 116
sp_cancel_adding_invoice 5977 5977 0 0.00 5 109727 804 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 8806 322 0 0
sp_cancel_customer_reserve 586111 586111 0 0.00 1 21095 63 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 2 10210 92 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 1084 58 0 0
sp_cancel_supplier_invoice 6666 6660 6 0.09 1 22168 836 0 6
sp_cancel_supplier_order 429 429 0 0.00 5 1146903 110337 0 0
sp_cancel_write_off 10725 10725 0 0.00 1 13854 109 0 0
sp_client_order 19708 19708 0 0.00 3 72031 334 0 0
sp_customer_reserve 437147 437147 0 0.00 0 58899 169 0 0
sp_fill_shopping_cart 30241 30241 0 0.00 1 14854 42 0 0
sp_fill_shopping_cart_clo_res 437146 437146 0 0.00 0 27575 13 0 0
sp_get_clo_for_invoice 4713 4713 0 0.00 0 30865 289 0 0
sp_kill_cost_storno 17728 17728 0 0.00 0 21423 62 0 0
sp_kill_qstorno_ret_qs2qd 1190036 1190036 0 0.00 0 22155 34 0 0
sp_kill_qty_storno 625920 625920 0 0.00 0 22160 73 0 0
sp_lock_selected_doc 19851 19851 0 0.00 0 4384 5 0 0
sp_make_cost_storno 26982 26982 0 0.00 0 25278 71 0 0
sp_multiply_rows_for_pdistr 26980 26980 0 0.00 0 9750 28 0 0
sp_multiply_rows_for_qdistr 19708 19708 0 0.00 0 67731 141 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 22760 156 0 0
sp_pay_to_supplier 5674 5674 0 0.00 5 22546 178 0 0
sp_payment_common 12630 12630 0 0.00 2 22032 33 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 8798 291 0 0
sp_qd_handle_on_invoice_upd_sts 10684 10684 0 0.00 0 22141 344 0 0
sp_qd_handle_on_reserve_upd_sts 9642 9642 0 0.00 0 19412 58 0 0
sp_reserve_write_off 9643 9643 0 0.00 6 25126 192 0 0
sp_supplier_invoice 5245 5245 0 0.00 7 133718 3586 0 0
sp_supplier_order 5292 5292 0 0.00 5 33149 1218 0 0
srv_aggregate_perf_data 108 108 0 0.00 2957 179615 17644 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 984 152552 51130 0 0
srv_find_qd_qs_mism 1063384 1063384 0 0.00 0 11899 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 39 130796 1186 0 0
srv_make_money_saldo 744 744 0 0.00 0 1576 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 176500 511013 361664 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4715 4715 0 0.00 0 7560 176 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 6902 96 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 1557 11 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 6579 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 654 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 693 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 5383 31 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 27785 1666 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 5767 17 0 0
t$perf-norm:sp_client_order 19708 19708 0 0.00 0 25873 22 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 2890 8 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 7786 11 0 0
t$perf-norm:sp_pay_to_supplier 5674 5674 0 0.00 0 4885 13 0 0
t$perf-norm:sp_reserve_write_off 9643 9643 0 0.00 0 22022 17 0 0
t$perf-norm:sp_supplier_invoice 5245 5245 0 0.00 0 10395 24 0 0
t$perf-norm:sp_supplier_order 5293 5293 0 0.00 0 6539 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 279 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 196 5 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 2245 29 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 1054 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9642 9410 232 2.40 0 9277 22 0 232
v_all_customers 36570 36570 0 0.00 0 20 1 0 0
v_all_suppliers 29426 29426 0 0.00 0 127 1 0 0
v_all_wares 39410 39410 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1713 9 0 0
v_cancel_client_order 3338 3338 0 0.00 0 2706 6 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 311 3 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 545 3 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 381 3 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 2694 21 0 12
v_cancel_supplier_order 858 858 0 0.00 0 606 4 0 0
v_cancel_write_off 1888 1888 0 0.00 0 4685 17 0 0
v_max_id_clo_ord 5292 5292 0 0.00 0 189 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 967 10 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 5460 5 0 0
v_max_non_paid_invoice 1497 1497 0 0.00 0 2494 4 0 0
v_max_non_paid_realizn 3477 3477 0 0.00 0 2031 4 0 0
v_min_id_clo_ord 5292 5292 0 0.00 0 11658 13 0 0
v_min_id_clo_res 983 983 0 0.00 0 3822 9 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 2229 4 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 21208 32 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 22025 23 0 0
v_our_firm 9798 9798 0 0.00 0 107 1 0 0
v_reserve_write_off 19282 19282 0 0.00 0 13219 10 0 0
x_lock_depdocs_on_canc_invoice 12248 12248 0 0.00 0 10338 115 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 9136 375 0 0
x_make_qstorno_1000_1200 5292 5292 0 0.00 1 33140 1130 0 0
x_make_qstorno_1200_2000 5245 5245 0 0.00 3 133709 3436 0 0
x_make_qstorno_2100_3300 437147 437147 0 0.00 0 58871 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2022 16:39:04 to 10-01-2022 16:39:08.

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
14:08:08 17 8192000000 8818090944 8836194304 20331040 0.247 6 4 5 0 20566592 96256 139296
14:09:08 17807 8192000000 9887931360 10197549056 982636992 10.710 100 81 162 0 1009306256 5540800 21128464
14:10:46 10121 8192000000 9951463664 10272133120 1033954112 11.207 100 88 176 0 1064558784 8246032 22358640
14:12:01 19682 8192000000 9976811264 10307239936 1058868976 11.446 100 81 162 1 1088486624 9370704 20246944
14:13:31 18117 8192000000 10036396192 10387468288 1121548736 12.042 100 76 152 0 1150604912 10814416 18241760
14:14:53 10504 8192000000 10084639088 10445189120 1168379984 12.482 100 79 158 0 1199435056 12540416 18514656
14:16:09 5363 8192000000 10139154320 10512949248 1227782048 13.034 100 87 171 0 1260282448 13178192 19322208
14:17:15 3210 8192000000 10180294528 10557386752 1258263552 13.314 100 81 159 2 1288453632 12011088 18178992
14:18:21 2589 8192000000 10209697712 10592595968 1288922816 13.594 100 91 182 1 1321927888 12229072 20776000
14:19:25 1336 8192000000 10230633088 10619052032 1310526144 13.791 100 94 187 1 1345186016 13590544 21069328
14:20:27 6207 8192000000 10206563472 10619564032 1316858768 13.848 100 30 60 0 1331023760 7774944 6390048
14:21:34 16599 8192000000 10217815504 10637484032 1326387328 13.935 100 13 26 0 1335044800 6055664 2601808
14:22:51 9155 8192000000 10219823856 10640371712 1330620352 13.973 100 25 49 1 1342516992 6767984 5128656
14:24:01 63475 8192000000 10234251744 10657087488 1339159328 14.050 100 24 48 0 1350438656 5681264 5598064
14:26:06 5411 8192000000 10236760672 10660110336 1343829872 14.092 100 22 44 1 1355360112 6727136 4803104
14:27:11 9923 8192000000 10229858064 10651553792 1337267552 14.033 100 15 30 0 1346078288 5025936 3784800
14:28:25 56421 8192000000 10180604240 10596737024 1242351584 13.168 95 38 74 3 1256516432 5683632 8481216
14:30:23 4955 8192000000 10119559184 10527219712 1223324080 12.992 98 25 50 0 1234866336 6015744 5526512
14:31:28 9571 8192000000 10153529216 10553470976 1246203040 13.203 99 18 35 0 1255594720 5445952 3945728
14:32:41 13272 8192000000 10156983440 10565390336 1260041680 13.330 99 34 66 2 1273440800 5956640 7442480
14:33:58 13877 8192000000 10170291200 10579509248 1276935536 13.485 100 14 28 0 1284941632 4850352 3155744
14:35:15 6819 8192000000 10185150848 10596618240 1292374512 13.626 100 19 38 2 1302445280 5678848 4391920
14:36:27 8134 8192000000 10193478432 10613489664 1303743200 13.729 100 21 42 1 1314299152 6048464 4507488
14:37:38 17456 8192000000 10208344256 10631233536 1322055632 13.895 100 12 24 0 1330437504 5078160 3303712
14:39:01 29574 8192000000 10254420656 10669051904 1348138288 14.131 100 38 76 1 1363219840 6861360 8220192
14:40:38 96148 8192000000 10264986752 10697498624 1364651632 14.279 100 27 54 3 1380265072 9347408 6266032
14:43:19 2337 8192000000 10295247216 10715242496 1380667168 14.423 100 62 124 0 1411151456 16638624 13845664
14:44:27 5138 8192000000 10320747984 10738814976 1396035248 14.560 100 57 110 1 1427918928 19257104 12626576
14:45:36 8222 8192000000 10319232304 10740813824 1391644320 14.521 100 59 115 1 1430441344 24677968 14119056
14:46:47 9031 8192000000 10331414064 10759122944 1406360800 14.652 100 41 81 1 1436425920 20331664 9733456
14:47:59 9527 8192000000 10353407744 10779320320 1422664768 14.796 100 52 104 2 1461224480 26048672 12511040
14:49:16 14431 8192000000 10365543728 10791784448 1430956576 14.870 100 59 118 0 1478489408 33075456 14457376
14:50:35 2522 8192000000 10367744048 10782560256 1414520528 14.724 99 80 159 2 1469223232 35670096 19032608
14:51:41 5320 8192000000 10384592272 10803798016 1431219744 14.872 100 74 147 0 1487298752 37402656 18676352
14:52:54 11234 8192000000 10391855520 10812559360 1434824912 14.904 100 63 126 1 1497141712 45938656 16378144
14:54:11 7411 8192000000 10374937648 10790457344 1411769072 14.700 100 62 124 1 1480232544 51787520 16675952
14:55:23 983 8192000000 10392783616 10802528256 1412616480 14.707 100 90 177 0 1495681984 59787824 23277680
14:56:33 3731 8192000000 10371647792 10776506368 1394692880 14.548 100 78 154 3 1471668448 56044080 20931488
14:57:40 2722 8192000000 10378126832 10782347264 1386292992 14.473 99 88 176 2 1466103728 57298096 22512640
14:58:52 6063 8192000000 10376617536 10795745280 1412871056 14.709 100 52 104 0 1481493360 54590256 14032048
15:00:04 8493 8192000000 10373681456 10796224512 1412034992 14.702 100 48 96 0 1478663504 53086352 13542160
15:01:18 10103 8192000000 10360881184 10789863424 1402393056 14.616 100 45 90 2 1466667552 51580000 12694496
15:02:30 11050 8192000000 10331634016 10773041152 1376400944 14.384 100 35 70 1 1434267264 48033856 9832464
15:04:22 30176 8192000000 10308335152 10760101888 1356724592 14.208 100 23 46 3 1424184160 60686160 6773408
15:05:53 18155 8192000000 10286123856 10728505344 1350568736 14.153 98 20 40 3 1373183040 17682016 4932288
15:07:13 67292 8192000000 10300876256 10751901696 1381947184 14.434 100 20 38 0 1406823104 19940240 4935680
15:09:37 78848 8192000000 10280059552 10733862912 1334244896 14.005 96 24 47 1 1352056560 12456384 5355280
15:11:57 110026 8192000000 10269515136 10726092800 1321742800 13.892 95 9 18 0 1339428880 15830704 1855376
15:14:47 71240 8192000000 10284278544 10742919168 1364341472 14.276 99 8 16 1 1378142880 12372720 1428688
15:16:59 76905 8192000000 10284854720 10740518912 1384648736 14.458 100 7 14 0 1394041856 7957200 1435920
15:19:19 64989 8192000000 10299027568 10761334784 1381139024 14.427 99 31 61 1 1399976000 11480240 7356736
15:22:14 103549 8192000000 10300442272 10756714496 1382099312 14.435 99 35 71 0 1404629600 14575584 7954704
15:24:59 27527 8192000000 10289139456 10755997696 1394129344 14.543 100 13 27 0 1407366384 10384768 2852272
15:26:46 58419 8192000000 10292876832 10759430144 1385176880 14.463 99 31 62 0 1402782816 10462240 7143696
15:28:45 59060 8192000000 10305174144 10770255872 1385798496 14.468 99 13 26 0 1401632224 12843616 2990112
15:31:08 78965 8192000000 10291837728 10756059136 1390999888 14.515 100 11 22 0 1409478096 15597808 2880400
15:33:42 60494 8192000000 10302493360 10781364224 1386298048 14.473 99 10 20 0 1402541408 13962080 2281280
15:35:43 76824 8192000000 10301094288 10754555904 1362529216 14.260 97 20 39 0 1379870736 13061776 4279744
15:38:00 97415 8192000000 10285362736 10754195456 1381774576 14.432 99 9 18 1 1395331360 11327952 2228832
15:40:40 63444 8192000000 10303222928 10762752000 1379548784 14.413 98 16 32 0 1391844288 8650400 3645104
15:42:50 78440 8192000000 10306156960 10778935296 1394399008 14.545 100 28 56 0 1416028480 15545696 6083776
15:45:33 109966 8192000000 10255589728 10707255296 1290584064 13.610 94 9 18 0 1303919008 11666128 1668816
15:48:23 152550 8192000000 10254443792 10694799360 1297180864 13.670 95 27 54 0 1316523168 12974192 6368112
15:51:57 65638 8192000000 10243137104 10683039744 1300040720 13.696 96 17 34 0 1311293120 7656816 3595584
15:54:02 46765 8192000000 10239301312 10693578752 1332871936 13.993 98 12 24 0 1343834976 8106496 2856544
15:56:02 69523 8192000000 10251913184 10709774336 1326867264 13.939 97 8 16 0 1335797472 6669488 2260720
15:58:18 50438 8192000000 10269150912 10716499968 1368315888 14.312 100 17 34 0 1384174224 12308432 3549904
16:00:14 62229 8192000000 10260029536 10720927744 1344761232 14.100 98 8 16 0 1360448208 13863696 1823280
16:02:17 28365 8192000000 10274339632 10707496960 1327163616 13.942 97 38 76 3 1345622704 9776352 8682736
16:03:48 31776 8192000000 10276672032 10724204544 1367894496 14.308 100 22 44 0 1393160896 19910976 5355424
16:05:20 61547 8192000000 10261623120 10712543232 1328396672 13.953 97 16 32 1 1341963024 10136272 3430080
16:07:24 57024 8192000000 10288953632 10736701440 1373938880 14.362 100 26 52 1 1394924768 15051680 5934208
16:09:23 29810 8192000000 10286142624 10723958784 1343622928 14.090 97 27 54 1 1361065200 11693808 5748464
16:11:07 91254 8192000000 10280622832 10746724352 1356986656 14.210 98 11 22 1 1387647808 28100144 2561008
16:13:40 98300 8192000000 10292486320 10735316992 1366861312 14.299 99 23 46 0 1382498816 10251152 5386352
16:16:26 72699 8192000000 10280029440 10732720128 1368717808 14.316 99 25 50 1 1386355520 12031264 5606448
16:18:39 90219 8192000000 10285157520 10749804544 1393475808 14.537 100 23 46 0 1409281168 10647248 5158112
16:21:36 54411 8192000000 10284323600 10746761216 1371233872 14.338 98 12 24 0 1383192384 9045216 2913296
16:23:32 73840 8192000000 10292469280 10757971968 1383216112 14.445 99 12 24 0 1400034368 13901472 2916784
16:25:46 99009 8192000000 10279208800 10738331648 1346507776 14.116 97 9 18 0 1360680544 12011008 2161760
16:28:31 104425 8192000000 10290065040 10751037440 1351725344 14.163 97 14 28 1 1369521440 14424128 3371968
16:31:44 55948 8192000000 10299106416 10750480384 1391853920 14.522 100 24 48 0 1409069712 12067952 5147840
16:33:43 30493 8192000000 10304783936 10754113536 1379097504 14.408 99 43 86 0 1402605600 13950624 9557472
16:35:14 60169 8192000000 10293262096 10758639616 1358027696 14.220 98 27 54 0 1381183392 17073824 6081872
16:37:25 56569 8192000000 8912454496 9310760960 4050400 0.049 1 1 2 0 4154848 62096 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2022 16:39:09 to 10-01-2022 16:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2022 16:39:25 to 10-01-2022 16:39:27.

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          237732
MON$OLDEST_ACTIVE               237733
MON$OLDEST_SNAPSHOT             237733
MON$NEXT_TRANSACTION            237733
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                       2026336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
--- end of diff output ---

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

10.01.22 16:39:28. 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
2022-01-10 16:39:30 No severe PSQL-related problems occured
10.01.22 16:39:31. End of report.