Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.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 16028228 kB
MemAvailable 30917484 kB
Buffers 0 kB
Cached 15677892 kB
SwapCached 2508 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.11
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-08-11 16:38:28
DTS_END                         2022-08-11 16:38:28.1990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47128
TRN_ID                          243103
ATT_ID                          640
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: 9110.13 19971 1093215
customer order: creation 174.73 309 20967
customer order: refuse 14.65 344 1758
order to supplier: creation 46.42 1232 5570
order to supplier: removal 3.40 91989 408
invoice (draft): creation 46.10 3533 5532
invoice (draft): removal 51.73 828 6207
invoice accept: apply 42.89 18113 5147
invoice accept: cancel 46.70 754 5604
customer reserve: creation 3826.07 186 459128
customer reserve: removal 4553.89 52 546467
realization accept: apply 85.73 169 10287
realization accept: cancel 86.60 101 10392
payment from customer: creation 60.07 120 7208
payment from customer: removal 5.94 84 713
payment to supplier: creation 49.68 123 5962
payment to supplier: removal 3.69 76 443
service: total inventory turnovers 4.41 1177 529
service: total monetary turnovers 6.44 37 773
service: aggregate perf. data 0.97 17977 116
service: refresh index statistics 0.03 262216 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2022 16:39:15 to 11-08-2022 16:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2135
TEST_TIME 2 2655
TEST_TIME 3 3481
TEST_TIME 4 4448
TEST_TIME 5 5791
TEST_TIME 6 5719
TEST_TIME 7 6072
TEST_TIME 8 6020
TEST_TIME 9 6385
TEST_TIME 10 6505
TEST_TIME 11 6320
TEST_TIME 12 6642
TEST_TIME 13 6830
TEST_TIME 14 7238
TEST_TIME 15 7331
TEST_TIME 16 7658
TEST_TIME 17 7940
TEST_TIME 18 8845
TEST_TIME 19 8750
TEST_TIME 20 8648
TEST_TIME 21 8704
TEST_TIME 22 8667
TEST_TIME 23 8722
TEST_TIME 24 8869
TEST_TIME 25 9043
TEST_TIME 26 9587
TEST_TIME 27 9848
TEST_TIME 28 10061
TEST_TIME 29 10333
TEST_TIME 30 10132
TEST_TIME 31 10201
TEST_TIME 32 9996
TEST_TIME 33 10085
TEST_TIME 34 10107
TEST_TIME 35 9977
TEST_TIME 36 9860
TEST_TIME 37 9686
TEST_TIME 38 9563
TEST_TIME 39 9558
TEST_TIME 40 9574
TEST_TIME 41 9480
TEST_TIME 42 9400
TEST_TIME 43 9365
TEST_TIME 44 9246
TEST_TIME 45 9239
TEST_TIME 46 9436
TEST_TIME 47 9393
TEST_TIME 48 9228
TEST_TIME 49 9207
TEST_TIME 50 9132
TEST_TIME 51 9132
TEST_TIME 52 8988
TEST_TIME 53 8990
TEST_TIME 54 8884
TEST_TIME 55 8967
TEST_TIME 56 9026
TEST_TIME 57 8962
TEST_TIME 58 8998
TEST_TIME 59 9146
TEST_TIME 60 9162
TEST_TIME 61 9123
TEST_TIME 62 9097
TEST_TIME 63 9222
TEST_TIME 64 9258
TEST_TIME 65 9277
TEST_TIME 66 9249
TEST_TIME 67 9263
TEST_TIME 68 9249
TEST_TIME 69 9268
TEST_TIME 70 9310
TEST_TIME 71 9301
TEST_TIME 72 9187
TEST_TIME 73 9192
TEST_TIME 74 9160
TEST_TIME 75 9150
TEST_TIME 76 9153
TEST_TIME 77 9123
TEST_TIME 78 9089
TEST_TIME 79 9126
TEST_TIME 80 9100
TEST_TIME 81 9055
TEST_TIME 82 9029
TEST_TIME 83 8952
TEST_TIME 84 8977
TEST_TIME 85 8954
TEST_TIME 86 8997
TEST_TIME 87 8959
TEST_TIME 88 9007
TEST_TIME 89 9075
TEST_TIME 90 9064
TEST_TIME 91 9121
TEST_TIME 92 9132
TEST_TIME 93 9142
TEST_TIME 94 9235
TEST_TIME 95 9231
TEST_TIME 96 9265
TEST_TIME 97 9201
TEST_TIME 98 9328
TEST_TIME 99 9293
TEST_TIME 100 9368
TEST_TIME 101 9291
TEST_TIME 102 9264
TEST_TIME 103 9192
TEST_TIME 104 9234
TEST_TIME 105 9197
TEST_TIME 106 9154
TEST_TIME 107 9116
TEST_TIME 108 9111
TEST_TIME 109 9069
TEST_TIME 110 9076
TEST_TIME 111 9057
TEST_TIME 112 9076
TEST_TIME 113 9071
TEST_TIME 114 9068
TEST_TIME 115 9089
TEST_TIME 116 9051
TEST_TIME 117 9115
TEST_TIME 118 9127
TEST_TIME 119 9149
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2022 16:39:19 to 11-08-2022 16:39:22.

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 1519866 1519866 0 0.00 0 13502 6 0 0
doc_list_biud 1519866 1519866 0 0.00 0 35071 25 0 0
sp_add_invoice_to_stock 5265 5147 118 2.24 30 160011 18113 0 118
sp_cancel_adding_invoice 5604 5604 0 0.00 4 52308 755 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 10602 344 0 0
sp_cancel_customer_reserve 546467 546467 0 0.00 0 32391 53 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 4708 85 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 4215 76 0 0
sp_cancel_supplier_invoice 6215 6207 8 0.12 1 35082 828 0 8
sp_cancel_supplier_order 408 408 0 0.00 6 1000484 91989 0 0
sp_cancel_write_off 10392 10392 0 0.00 1 15805 102 0 0
sp_client_order 20967 20967 0 0.00 3 37208 309 0 0
sp_customer_reserve 459128 459128 0 0.00 0 59672 186 0 0
sp_fill_shopping_cart 32051 32051 0 0.00 1 19366 44 0 0
sp_fill_shopping_cart_clo_res 459125 459125 0 0.00 0 12710 14 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 23484 318 0 0
sp_kill_cost_storno 17152 17152 0 0.00 0 6746 50 0 0
sp_kill_qstorno_ret_qs2qd 1109941 1109941 0 0.00 0 35063 29 0 0
sp_kill_qty_storno 586262 586262 0 0.00 0 35068 64 0 0
sp_lock_selected_doc 21326 21326 0 0.00 0 6691 4 0 0
sp_make_cost_storno 28596 28596 0 0.00 0 12761 49 0 0
sp_multiply_rows_for_pdistr 28596 28596 0 0.00 0 7520 25 0 0
sp_multiply_rows_for_qdistr 20967 20967 0 0.00 0 37198 126 0 0
sp_pay_from_customer 7208 7208 0 0.00 4 13966 120 0 0
sp_pay_to_supplier 5962 5962 0 0.00 4 15930 123 0 0
sp_payment_common 13170 13170 0 0.00 1 14663 36 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 10597 323 0 0
sp_qd_handle_on_invoice_upd_sts 10743 10743 0 0.00 0 18623 324 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 27523 58 0 0
sp_reserve_write_off 10287 10287 0 0.00 6 27618 170 0 0
sp_supplier_invoice 5532 5532 0 0.00 5 97382 3534 0 0
sp_supplier_order 5570 5570 0 0.00 4 41311 1232 0 0
srv_aggregate_perf_data 116 116 0 0.00 2609 137299 17978 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 737 186212 47030 0 0
srv_find_qd_qs_mism 1045911 1045911 0 0.00 0 13501 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 31 66008 1178 0 0
srv_make_money_saldo 773 773 0 0.00 1 6683 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 132807 384683 262216 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5147 5147 0 0.00 0 10464 154 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 4273 98 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 1193 10 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 6434 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 864 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 556 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4484 30 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 22168 1447 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 890 5 0 0
t$perf-norm:sp_client_order 20967 20967 0 0.00 0 10180 18 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 4911 10 0 0
t$perf-norm:sp_pay_from_customer 7208 7208 0 0.00 0 5294 8 0 0
t$perf-norm:sp_pay_to_supplier 5962 5962 0 0.00 0 5708 8 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 8788 11 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 11893 24 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 5283 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 378 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 1815 26 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 1385 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10514 10278 236 2.24 0 4119 16 0 236
v_all_customers 38042 38042 0 0.00 0 18 1 0 0
v_all_suppliers 30362 30362 0 0.00 0 82 1 0 0
v_all_wares 41934 41934 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 190 6 0 0
v_cancel_client_order 3516 3516 0 0.00 0 3353 5 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 3638 7 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 708 5 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 675 4 0 0
v_cancel_supplier_invoice 844 828 16 1.89 0 1329 16 0 16
v_cancel_supplier_order 816 816 0 0.00 0 3151 10 0 0
v_cancel_write_off 2034 2034 0 0.00 0 5749 18 0 0
v_max_id_clo_ord 5570 5570 0 0.00 0 2925 2 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 1822 12 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 8090 7 0 0
v_max_non_paid_invoice 1865 1865 0 0.00 0 3377 5 0 0
v_max_non_paid_realizn 3824 3824 0 0.00 0 5914 6 0 0
v_min_id_clo_ord 5570 5570 0 0.00 0 2568 7 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 4053 8 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 6511 8 0 0
v_min_non_paid_invoice 5962 5962 0 0.00 0 14660 20 0 0
v_min_non_paid_realizn 7208 7208 0 0.00 0 7494 28 0 0
v_our_firm 10660 10660 0 0.00 0 31 1 0 0
v_reserve_write_off 20574 20574 0 0.00 0 3554 5 0 0
x_lock_depdocs_on_canc_invoice 11397 11397 0 0.00 0 21673 115 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 27272 387 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 1 41295 1156 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 2 89656 3348 0 0
x_make_qstorno_2100_3300 459128 459128 0 0.00 0 59670 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2022 16:39:23 to 11-08-2022 16:39:27.

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:32 2 8192000000 8799916240 8811675648 6079440 0.074 3 1 2 0 6180368 58144 42784
14:09:32 26160 8192000000 9899249536 10209579008 978643536 10.671 100 88 176 0 1008436208 6176960 23615712
14:11:02 21592 8192000000 9956106832 10278879232 1037129632 11.237 100 92 184 0 1068417920 7665472 23622816
14:12:26 19043 8192000000 9997846192 10332377088 1071046912 11.562 100 84 168 0 1105678192 13773856 20857424
14:13:51 6327 8192000000 10044252816 10391527424 1126025600 12.084 100 75 149 0 1154295248 11105040 17164608
14:15:02 4292 8192000000 10082937072 10445283328 1174415344 12.538 100 79 156 1 1204454368 12547600 17491424
14:16:10 4929 8192000000 10126684864 10496942080 1214642928 12.912 100 79 157 0 1245376032 13057344 17675760
14:17:16 2798 8192000000 10160061504 10535686144 1247556400 13.216 100 80 158 1 1278472288 13612240 17303648
14:18:21 1026 8192000000 10195378976 10576039936 1283899600 13.549 100 82 162 0 1314861328 12919136 18042592
14:19:23 1720 8192000000 10212026720 10596622336 1296404656 13.663 100 89 179 1 1327756384 11729760 19621968
14:20:26 8492 8192000000 10203366304 10600947712 1303474656 13.727 100 68 135 0 1329508480 11168480 14865344
14:21:36 8809 8192000000 10199114640 10610253824 1313085904 13.814 100 30 60 1 1326486592 6879856 6520832
14:22:45 6893 8192000000 10206659728 10623160320 1324936144 13.921 100 19 38 0 1335122688 5989744 4196800
14:23:53 54944 8192000000 10222596496 10647089152 1330130112 13.968 100 21 42 2 1344834720 9528704 5175904
14:25:51 9741 8192000000 10227831264 10645803008 1324740752 13.920 99 30 60 0 1337791872 6127232 6923888
14:27:04 5597 8192000000 10225871696 10649239552 1326370752 13.934 99 20 40 0 1337879264 7272736 4235776
14:28:12 64727 8192000000 10185428288 10606108672 1264916560 13.375 95 30 60 0 1277809264 4594336 8298368
14:30:21 6367 8192000000 10174437440 10572808192 1244672224 13.189 97 25 50 0 1255668720 5431616 5564880
14:31:28 5090 8192000000 10144744336 10541731840 1235746288 13.107 98 30 60 0 1249203168 5959408 7497472
14:32:33 6798 8192000000 10154129136 10552692736 1247480800 13.215 99 31 63 0 1260437952 5434352 7522800
14:33:40 30804 8192000000 10152694832 10560155648 1233668752 13.088 97 30 59 0 1246419536 5991696 6759088
14:35:13 8364 8192000000 10177670896 10589585408 1283499584 13.545 100 39 78 1 1303992976 11426144 9067248
14:36:22 8933 8192000000 10184324176 10596159488 1291373776 13.617 100 13 24 0 1299313280 4929584 3009920
14:37:35 18751 8192000000 10209673504 10620133376 1312839616 13.812 100 29 58 1 1325579776 5076544 7663616
14:38:57 30158 8192000000 10237957056 10650247168 1337728608 14.037 100 34 64 0 1351331600 6275904 7327088
14:40:35 24297 8192000000 10261370608 10677309440 1356112432 14.202 100 47 92 0 1378619520 12632320 9874768
14:42:03 14683 8192000000 10284753680 10702520320 1368928400 14.317 100 43 84 0 1395418368 16952176 9537792
14:43:21 12155 8192000000 10288455568 10712657920 1373370112 14.357 100 39 77 0 1398011440 15924384 8716944
14:44:57 4796 8192000000 10304680816 10730811392 1392920448 14.532 100 47 92 1 1417811408 13453136 11437824
14:46:08 4793 8192000000 10316458960 10747604992 1407183072 14.659 100 45 88 0 1431469920 13664832 10622016
14:47:15 7432 8192000000 10332325136 10754437120 1399607664 14.592 99 60 119 1 1431189888 17810336 13771888
14:48:29 5597 8192000000 10329791024 10748977152 1402107216 14.614 99 65 124 1 1435571744 18163728 15300800
14:49:40 15770 8192000000 10306164048 10726105088 1362075072 14.256 97 52 104 0 1396141136 21018992 13047072
14:50:59 737 8192000000 10325077408 10731597824 1384864080 14.460 100 77 150 0 1428355920 25438368 18053472
14:52:04 1682 8192000000 10336543392 10750980096 1394967760 14.550 100 62 121 1 1439869040 29885968 15015312
14:53:12 2487 8192000000 10317488528 10735955968 1373753904 14.361 99 62 124 1 1418550288 29221792 15574592
14:54:21 8359 8192000000 10330056768 10746507264 1376174608 14.382 100 69 137 1 1430345008 36423392 17747008
14:55:36 3617 8192000000 10305676768 10714521600 1348410464 14.133 100 70 138 0 1407400080 41299952 17689664
14:56:48 11879 8192000000 10303131856 10727034880 1370897936 14.335 100 54 108 0 1410964528 26292880 13773712
14:58:16 2125 8192000000 10338445776 10745634816 1384747280 14.459 100 77 151 2 1431717248 28269344 18700624
14:59:20 5002 8192000000 10307940128 10733436928 1375974016 14.381 100 44 86 0 1419579968 32601920 11004032
15:00:33 5557 8192000000 10312838768 10733752320 1370736400 14.334 100 35 70 0 1413819024 33058944 10023680
15:01:44 17868 8192000000 10313075008 10741264384 1363579200 14.269 99 27 52 0 1410518272 39767216 7171856
15:03:07 10742 8192000000 10297011120 10725044224 1350337488 14.151 100 38 76 0 1403893344 41932032 11623824
15:04:23 31237 8192000000 10279695200 10713870336 1329222560 13.960 98 21 42 0 1373217744 37612416 6382768
15:05:56 39754 8192000000 10289836256 10729816064 1342394848 14.079 99 17 34 0 1375921056 28758800 4767408
15:07:38 52261 8192000000 10292234560 10727956480 1368863440 14.317 100 29 59 0 1391890128 16141792 6884896
15:09:37 85305 8192000000 10274801168 10714066944 1334169824 14.005 97 27 53 0 1356702208 17185472 5346912
15:12:10 86556 8192000000 10271911728 10719227904 1354612352 14.189 99 14 27 0 1373120576 14474176 4034048
15:14:38 73263 8192000000 10290545872 10727915520 1374801376 14.370 100 43 84 1 1391182320 6123008 10257936
15:16:57 41825 8192000000 10285802288 10735030272 1372763360 14.352 99 39 78 0 1393745312 12019728 8962224
15:19:07 93580 8192000000 10295600896 10746544128 1384207328 14.454 99 26 50 0 1405211392 14939440 6064624
15:21:42 43331 8192000000 10302969200 10749800448 1381628688 14.431 99 21 43 0 1403236832 16048080 5560064
15:23:35 14076 8192000000 10314181808 10753789952 1400827488 14.602 100 48 94 0 1427076784 15632304 10616992
15:25:00 56793 8192000000 10283342064 10749730816 1379823488 14.415 99 10 20 0 1396874928 14820320 2231120
15:27:01 66129 8192000000 10289860896 10758586368 1386911616 14.478 99 13 27 1 1402009584 12018544 3079424
15:29:14 47127 8192000000 10289708976 10765729792 1385257872 14.464 99 7 14 0 1398444608 11710560 1476176
15:31:02 73648 8192000000 10294685440 10759122944 1372177408 14.347 98 21 41 0 1386341120 9611488 4552224
15:33:16 112677 8192000000 10289907264 10753794048 1357774800 14.217 97 16 31 0 1382995520 21457728 3762992
15:36:09 85562 8192000000 10292545104 10762817536 1370711344 14.333 98 10 20 0 1392425088 19326912 2386832
15:39:16 31821 8192000000 10279784256 10724057088 1344084208 14.094 97 26 50 0 1356972448 7356336 5531904
15:40:52 14202 8192000000 10312771728 10754109440 1392961392 14.532 100 42 82 1 1414006464 12190576 8854496
15:42:07 68986 8192000000 10283338480 10733789184 1348199744 14.131 98 20 40 1 1364831024 12142992 4488288
15:44:24 59614 8192000000 10283757888 10722865152 1349693344 14.145 97 35 70 0 1368238256 11056336 7488576
15:46:25 65383 8192000000 10285396336 10722820096 1330504160 13.972 96 28 56 0 1346981728 10758848 5718720
15:48:30 120664 8192000000 10273445296 10715185152 1300458304 13.699 95 12 24 0 1312357520 9600960 2298256
15:51:31 80600 8192000000 10261883216 10718744576 1341867392 14.074 98 16 32 0 1358736208 13455680 3413136
15:53:57 43463 8192000000 10262710352 10706083840 1322748528 13.902 97 24 43 2 1341875472 14146240 4980704
15:55:58 54483 8192000000 10267614480 10710700032 1347200576 14.122 99 15 30 0 1363469888 12658928 3610384
15:57:58 50867 8192000000 10229643440 10673729536 1334257120 14.006 99 9 16 0 1342090576 6065600 1767856
15:59:50 55966 8192000000 10240524240 10695004160 1346686848 14.118 100 13 26 0 1360939536 11178240 3074448
16:01:46 66993 8192000000 10237282864 10696716288 1330971552 13.976 99 9 18 0 1344166944 11191584 2003808
16:03:57 75972 8192000000 10257713696 10709536768 1341616704 14.072 99 15 30 0 1356657680 11531216 3509760
16:06:16 87049 8192000000 10253834880 10713333760 1333947104 14.003 98 16 32 0 1350365232 12910416 3507712
16:08:46 48982 8192000000 10269184608 10717446144 1349306112 14.141 98 20 40 0 1364934752 11544784 4083856
16:10:40 139920 8192000000 10294107184 10760826880 1373894656 14.362 99 11 22 0 1392993776 16577104 2522016
16:14:00 70486 8192000000 10320513840 10771333120 1401162944 14.605 100 27 54 0 1422347696 15620336 5564416
16:16:11 67391 8192000000 10307150784 10770395136 1372479600 14.349 98 22 44 0 1396537072 19313552 4743920
16:18:22 186212 8192000000 10292796944 10743865344 1340515936 14.062 96 19 38 1 1358707600 13714304 4477360
16:22:30 45360 8192000000 10269373344 10731864064 1354332832 14.186 98 6 12 0 1366112064 10234480 1544752
16:24:15 47775 8192000000 10267333184 10722877440 1372819232 14.352 100 8 16 0 1381884336 7355552 1709552
16:26:03 94479 8192000000 10288089920 10754387968 1352539856 14.170 97 9 18 0 1367024592 12311232 2173504
16:29:04 64246 8192000000 10281445776 10730668032 1348920576 14.138 97 11 22 0 1363549440 12235776 2393088
16:31:09 38895 8192000000 10294022528 10748813312 1385397072 14.465 100 18 36 1 1401939088 12615616 3926400
16:33:01 11816 8192000000 10275983120 10724220928 1373600720 14.359 100 29 58 0 1388630000 8582272 6447008
16:34:18 28388 8192000000 10283119328 10724446208 1341526592 14.071 97 48 96 0 1364744752 12377200 10840960
16:35:54 77111 8192000000 10271930608 10723966976 1309637888 13.783 95 16 32 0 1331914176 18686784 3589504
16:38:17 19825 8192000000 8934356688 9180880896 82566624 0.997 7 2 4 0 83726208 798848 360736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2022 16:39:29 to 11-08-2022 16:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-08-2022 16:39:45 to 11-08-2022 16:39:46.

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          243826
MON$OLDEST_ACTIVE               243827
MON$OLDEST_SNAPSHOT             243827
MON$NEXT_TRANSACTION            243827
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                       2018880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

11.08.22 16:39:47. 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-08-11 16:39:50 No severe PSQL-related problems occured
11.08.22 16:39:51. End of report.