Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.03.2023 07:47
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 14665016 kB
MemAvailable 30444456 kB
Buffers 0 kB
Cached 17042744 kB
SwapCached 3236 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-03-15 07:46:18
DTS_END                         2023-03-15 07:46:18.7070
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(68:17)
IP                              ::1/42950
TRN_ID                          223944
ATT_ID                          649
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: 9488.81 32112 1138657
customer order: creation 166.63 473 19996
customer order: refuse 14.22 428 1706
order to supplier: creation 45.73 1332 5487
order to supplier: removal 3.44 185279 413
invoice (draft): creation 46.88 3118 5625
invoice (draft): removal 53.91 962 6469
invoice accept: apply 41.86 24546 5023
invoice accept: cancel 48.91 1148 5869
customer reserve: creation 3919.75 228 470370
customer reserve: removal 4851.29 101 582155
realization accept: apply 83.51 293 10021
realization accept: cancel 81.81 179 9817
payment from customer: creation 59.35 183 7122
payment from customer: removal 5.66 116 679
payment to supplier: creation 49.46 222 5935
payment to supplier: removal 3.33 158 400
service: total inventory turnovers 4.07 4053 488
service: total monetary turnovers 7.93 363 952
service: aggregate perf. data 1.05 15487 126
service: refresh index statistics 0.03 403572 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-03-2023 07:47:52 to 15-03-2023 07:47:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7038
TEST_TIME 2 6048
TEST_TIME 3 6608
TEST_TIME 4 6259
TEST_TIME 5 6862
TEST_TIME 6 7062
TEST_TIME 7 6778
TEST_TIME 8 7012
TEST_TIME 9 6941
TEST_TIME 10 7139
TEST_TIME 11 7250
TEST_TIME 12 7359
TEST_TIME 13 7263
TEST_TIME 14 7319
TEST_TIME 15 7126
TEST_TIME 16 7182
TEST_TIME 17 7444
TEST_TIME 18 7638
TEST_TIME 19 7608
TEST_TIME 20 7472
TEST_TIME 21 7670
TEST_TIME 22 7893
TEST_TIME 23 7734
TEST_TIME 24 7764
TEST_TIME 25 8068
TEST_TIME 26 8058
TEST_TIME 27 8234
TEST_TIME 28 8616
TEST_TIME 29 8742
TEST_TIME 30 9181
TEST_TIME 31 9410
TEST_TIME 32 9397
TEST_TIME 33 9548
TEST_TIME 34 10182
TEST_TIME 35 10536
TEST_TIME 36 10885
TEST_TIME 37 11416
TEST_TIME 38 11407
TEST_TIME 39 11186
TEST_TIME 40 11378
TEST_TIME 41 11353
TEST_TIME 42 11243
TEST_TIME 43 11168
TEST_TIME 44 11045
TEST_TIME 45 11091
TEST_TIME 46 11085
TEST_TIME 47 10998
TEST_TIME 48 11055
TEST_TIME 49 10899
TEST_TIME 50 10773
TEST_TIME 51 10648
TEST_TIME 52 10568
TEST_TIME 53 10573
TEST_TIME 54 10581
TEST_TIME 55 10432
TEST_TIME 56 10362
TEST_TIME 57 10336
TEST_TIME 58 10284
TEST_TIME 59 10355
TEST_TIME 60 10310
TEST_TIME 61 10263
TEST_TIME 62 10196
TEST_TIME 63 10176
TEST_TIME 64 10178
TEST_TIME 65 10156
TEST_TIME 66 10110
TEST_TIME 67 10104
TEST_TIME 68 10124
TEST_TIME 69 10208
TEST_TIME 70 10253
TEST_TIME 71 10233
TEST_TIME 72 10243
TEST_TIME 73 10260
TEST_TIME 74 10236
TEST_TIME 75 10300
TEST_TIME 76 10230
TEST_TIME 77 10217
TEST_TIME 78 10261
TEST_TIME 79 10249
TEST_TIME 80 10163
TEST_TIME 81 10196
TEST_TIME 82 10138
TEST_TIME 83 10074
TEST_TIME 84 10026
TEST_TIME 85 10026
TEST_TIME 86 10035
TEST_TIME 87 9968
TEST_TIME 88 9931
TEST_TIME 89 9847
TEST_TIME 90 9840
TEST_TIME 91 9855
TEST_TIME 92 9804
TEST_TIME 93 9750
TEST_TIME 94 9702
TEST_TIME 95 9625
TEST_TIME 96 9600
TEST_TIME 97 9635
TEST_TIME 98 9608
TEST_TIME 99 9646
TEST_TIME 100 9620
TEST_TIME 101 9615
TEST_TIME 102 9661
TEST_TIME 103 9682
TEST_TIME 104 9696
TEST_TIME 105 9704
TEST_TIME 106 9715
TEST_TIME 107 9689
TEST_TIME 108 9669
TEST_TIME 109 9781
TEST_TIME 110 9788
TEST_TIME 111 9751
TEST_TIME 112 9696
TEST_TIME 113 9670
TEST_TIME 114 9648
TEST_TIME 115 9626
TEST_TIME 116 9597
TEST_TIME 117 9585
TEST_TIME 118 9551
TEST_TIME 119 9573
TEST_TIME 120 9518

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-03-2023 07:47:56 to 15-03-2023 07:48:00.

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 1571568 1571568 0 0.00 0 19743 13 0 0
doc_list_biud 1571555 1571555 0 0.00 0 61789 42 0 0
sp_add_invoice_to_stock 5134 5023 111 2.16 74 165117 24546 0 111
sp_cancel_adding_invoice 5869 5869 0 0.00 6 93874 1148 0 0
sp_cancel_client_order 1706 1706 0 0.00 6 17215 428 0 0
sp_cancel_customer_reserve 582155 582155 0 0.00 1 61820 102 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 4 5388 117 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 5606 158 0 0
sp_cancel_supplier_invoice 6483 6469 14 0.21 3 15183 963 0 14
sp_cancel_supplier_order 413 413 0 0.00 9 1589868 185280 0 0
sp_cancel_write_off 9817 9817 0 0.00 2 9906 180 0 0
sp_client_order 19996 19996 0 0.00 6 34605 473 0 0
sp_customer_reserve 470370 470370 0 0.00 1 130056 229 0 0
sp_fill_shopping_cart 31095 31095 0 0.00 1 17545 85 0 0
sp_fill_shopping_cart_clo_res 470335 470335 0 0.00 0 16364 17 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 26542 266 0 0
sp_kill_cost_storno 16765 16765 0 0.00 0 5491 74 0 0
sp_kill_qstorno_ret_qs2qd 1181009 1181009 0 0.00 0 61686 42 0 0
sp_kill_qty_storno 621426 621426 0 0.00 0 61778 98 0 0
sp_lock_selected_doc 20743 20743 0 0.00 0 6159 8 0 0
sp_make_cost_storno 28055 28055 0 0.00 0 19725 89 0 0
sp_multiply_rows_for_pdistr 28054 28054 0 0.00 0 9572 38 0 0
sp_multiply_rows_for_qdistr 19996 19996 0 0.00 0 18011 167 0 0
sp_pay_from_customer 7122 7122 0 0.00 6 14082 184 0 0
sp_pay_to_supplier 5935 5935 0 0.00 6 32248 223 0 0
sp_payment_common 13056 13056 0 0.00 2 32197 53 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 1 17182 341 0 0
sp_qd_handle_on_invoice_upd_sts 10845 10845 0 0.00 0 17675 546 0 0
sp_qd_handle_on_reserve_upd_sts 10021 10021 0 0.00 0 21887 65 0 0
sp_reserve_write_off 10021 10021 0 0.00 8 21933 294 0 0
sp_supplier_invoice 5625 5625 0 0.00 11 116576 3118 0 0
sp_supplier_order 5487 5487 0 0.00 7 35283 1332 0 0
srv_aggregate_perf_data 126 126 0 0.00 2491 106712 15487 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 781 155039 37171 0 0
srv_find_qd_qs_mism 1089442 1089442 0 0.00 0 12171 9 0 0
srv_make_invnt_saldo 488 488 0 0.00 62 74090 4053 0 0
srv_make_money_saldo 952 952 0 0.00 1 13825 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 207242 547324 403572 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 1 15836 1002 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 12070 474 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 1388 28 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 2778 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 4741 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 354 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2802 43 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 95608 8506 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 10086 34 0 0
t$perf-norm:sp_client_order 19997 19997 0 0.00 0 10560 40 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 2453 20 0 0
t$perf-norm:sp_pay_from_customer 7122 7122 0 0.00 0 5047 22 0 0
t$perf-norm:sp_pay_to_supplier 5935 5935 0 0.00 0 6738 24 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 5670 24 0 0
t$perf-norm:sp_supplier_invoice 5625 5625 0 0.00 0 8297 34 0 0
t$perf-norm:sp_supplier_order 5487 5487 0 0.00 0 3295 37 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 880 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 3048 55 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 708 12 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 2324 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 124 34 0 0
v_add_invoice_to_stock 10160 9938 222 2.18 0 5831 30 0 222
v_all_customers 37196 37196 0 0.00 0 76 4 0 0
v_all_suppliers 31296 31296 0 0.00 0 72 5 0 0
v_all_wares 39984 39984 0 0.00 0 114 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3246 19 0 0
v_cancel_client_order 3412 3412 0 0.00 0 3556 13 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 786 11 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2621 16 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 5598 19 0 0
v_cancel_supplier_invoice 876 848 28 3.19 0 2497 30 0 28
v_cancel_supplier_order 826 826 0 0.00 0 1283 13 0 0
v_cancel_write_off 2076 2076 0 0.00 0 3443 26 0 0
v_max_id_clo_ord 5485 5485 0 0.00 0 5293 6 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 7068 19 0 0
v_max_id_ord_sup 5617 5617 0 0.00 0 2903 6 0 0
v_max_non_paid_invoice 1389 1389 0 0.00 0 3674 12 0 0
v_max_non_paid_realizn 3592 3592 0 0.00 0 3674 11 0 0
v_min_id_clo_ord 5485 5485 0 0.00 0 1456 9 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 7807 25 0 0
v_min_id_ord_sup 5617 5617 0 0.00 0 2625 8 0 0
v_min_non_paid_invoice 5935 5935 0 0.00 0 32178 25 0 0
v_min_non_paid_realizn 7121 7121 0 0.00 0 5979 20 0 0
v_our_firm 9846 9846 0 0.00 0 64 5 0 0
v_reserve_write_off 20042 20042 0 0.00 0 5060 12 0 0
x_lock_depdocs_on_canc_invoice 11914 11914 0 0.00 0 12121 140 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 20896 390 0 0
x_make_qstorno_1000_1200 5487 5487 0 0.00 2 35247 1141 0 0
x_make_qstorno_1200_2000 5625 5625 0 0.00 4 116322 2919 0 0
x_make_qstorno_2100_3300 470369 470369 0 0.00 0 130052 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-03-2023 07:48:01 to 15-03-2023 07:48:05.

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
05:16:22 863 8192000000 8926435600 8983093248 46090064 0.559 9 8 13 0 47422032 313840 1018128
05:17:24 22943 8192000000 9706220528 10000207872 782840160 8.722 100 88 174 1 812386000 7121376 22424464
05:18:49 14770 8192000000 9749733968 10054475776 831581232 9.215 100 89 178 0 863673936 10687424 21405280
05:20:12 14567 8192000000 9782413872 10098966528 858178544 9.482 100 84 168 1 893993056 16650976 19163536
05:21:29 7363 8192000000 9839849168 10163298304 893143616 9.830 100 85 169 1 944942608 33487168 18311824
05:22:40 3958 8192000000 9918353056 10253234176 935598336 10.250 100 89 176 1 1024665072 70508336 18558400
05:23:47 3330 8192000000 9948652096 10294960128 981223712 10.696 100 89 176 0 1065308624 66447376 17637536
05:24:54 1991 8192000000 9989553328 10345213952 1005473152 10.932 100 90 174 0 1107774464 85190368 17110944
05:25:57 914 8192000000 10007244976 10373910528 1026432064 11.134 100 98 188 1 1123739264 78369648 18937552
05:27:01 1009 8192000000 10002162144 10374426624 1041750256 11.281 100 93 183 1 1126622672 66222032 18650384
05:28:02 1537 8192000000 10013025968 10390745088 1060085600 11.457 100 93 181 1 1135756784 57487360 18183824
05:29:06 1217 8192000000 10028845808 10404204544 1065576176 11.510 100 96 177 0 1149986784 65973744 18436864
05:30:08 1359 8192000000 10038202912 10417025024 1074380624 11.594 100 82 159 0 1160119904 70397440 15341840
05:31:11 1276 8192000000 10051683840 10436050944 1078400320 11.632 100 85 157 4 1172610272 78147680 16062272
05:32:13 1113 8192000000 10037991344 10422947840 1081670656 11.663 100 95 185 2 1160798064 59284704 19842704
05:33:16 1234 8192000000 10062703744 10448756736 1084477264 11.690 100 91 174 0 1182955744 80175888 18302592
05:34:18 1376 8192000000 10060610848 10448019456 1090504080 11.747 100 89 169 0 1186962848 79544832 16913936
05:35:21 978 8192000000 10073892912 10460549120 1096697616 11.806 100 94 181 0 1196025840 81313072 18015152
05:36:23 1160 8192000000 10083426256 10469715968 1092858448 11.770 100 98 189 1 1203297888 90858784 19580656
05:37:25 1191 8192000000 10053400640 10437455872 1088794880 11.731 100 96 183 0 1174318448 67646768 17876800
05:38:27 1070 8192000000 10040876320 10427953152 1081587264 11.663 99 94 164 1 1164528800 64146176 18795360
05:39:28 2955 8192000000 10044742080 10431221760 1074226112 11.592 100 74 146 3 1162486944 73832912 14427920
05:40:32 2303 8192000000 10016689424 10398957568 1062908144 11.484 100 86 157 2 1140045728 60402464 16735120
05:41:35 1037 8192000000 9986238320 10358259712 1036954368 11.235 100 88 161 2 1108603808 54137616 17511824
05:42:37 1174 8192000000 9977775584 10348650496 1028192864 11.151 99 87 162 2 1100855744 56354736 16308144
05:43:39 1108 8192000000 10004933936 10380689408 1048088144 11.342 100 89 162 5 1124899200 59426368 17384688
05:44:40 1507 8192000000 9983432704 10361630720 1027616560 11.145 100 82 149 2 1107850608 64873968 15360080
05:45:43 902 8192000000 9977517520 10356125696 1043265600 11.296 100 83 157 1 1105637792 43612080 18760112
05:46:53 16018 8192000000 9985512272 10373570560 1051207968 11.372 100 58 113 1 1104495664 41765152 11522544
05:48:15 4680 8192000000 10014749744 10397958144 1055239184 11.411 99 67 122 1 1128512144 60520112 12752848
05:49:29 1706 8192000000 10058771472 10439602176 1067439136 11.528 100 93 172 1 1182011184 95008352 19563696
05:50:36 5229 8192000000 10086094080 10470789120 1084834848 11.694 100 75 143 0 1208643472 109126720 14681904
05:51:45 4094 8192000000 10085837776 10483638272 1106063376 11.895 100 77 149 1 1209301840 87374912 15863552
05:52:53 5301 8192000000 10108137920 10495762432 1107381008 11.908 100 86 154 2 1222658656 97744944 17532704
05:54:04 7625 8192000000 10167528272 10576506880 1115672704 11.986 100 62 122 0 1276307536 146996080 13638752
05:55:16 1285 8192000000 10188897072 10586669056 1135733856 12.175 100 92 178 2 1304152464 147850640 20567968
05:56:20 1023 8192000000 10240373552 10642366464 1150059360 12.310 100 88 168 3 1353705936 185349696 18296880
05:57:25 12587 8192000000 10234561904 10635071488 1151116528 12.320 99 74 136 0 1353065296 187494400 14454368
05:58:43 4930 8192000000 10265737824 10673762304 1177873024 12.570 100 72 143 0 1377433328 184064448 15495856
05:59:50 1191 8192000000 10310727712 10718580736 1173283776 12.528 99 93 184 2 1422247040 228853136 20110128
06:00:54 1463 8192000000 10348592688 10755604480 1197365392 12.752 100 92 180 2 1459268016 241888656 20013968
06:01:59 973 8192000000 10384537136 10810626048 1198134048 12.759 100 96 188 0 1492196416 272419472 21642896
06:03:04 1255 8192000000 10397799920 10839375872 1214731424 12.913 100 92 182 2 1516984032 279678416 22574192
06:04:08 2674 8192000000 10433566928 10841120768 1218622880 12.949 100 86 169 0 1533570496 294946112 20001504
06:05:14 972 8192000000 10497760128 10909745152 1223080064 12.990 100 89 177 0 1605379392 360355472 21943856
06:06:20 1292 8192000000 10523460384 10947289088 1220327344 12.965 100 96 192 1 1628846448 384914048 23605056
06:07:24 836 8192000000 10559256240 10983591936 1210146512 12.870 100 98 191 0 1666003104 431873728 23982864
06:08:27 1480 8192000000 10557184832 10971025408 1187665952 12.662 98 92 180 1 1663317600 453986176 21665472
06:09:32 801 8192000000 10637301216 11045920768 1224636576 13.005 100 94 179 0 1744721344 498642208 21442560
06:10:35 1290 8192000000 10654750720 11099443200 1223946032 12.998 100 95 185 0 1758789296 511403392 23439872
06:11:39 2080 8192000000 10685369472 11130654720 1223414416 12.993 100 98 194 1 1797997792 549760096 24823280
06:12:45 3309 8192000000 10728780752 11171188736 1224355600 13.002 100 96 192 1 1828129840 580676000 23098240
06:13:50 1255 8192000000 10692549808 11177451520 1211434368 12.882 100 84 164 1 1795598768 562676288 21488112
06:14:54 777 8192000000 10707965360 11147186176 1214705472 12.913 100 78 155 0 1809590224 574853392 20031360
06:16:11 14414 8192000000 10623390976 11115364352 1206055568 12.833 100 43 82 1 1751756688 534591872 11109248
06:17:27 26210 8192000000 10583865392 11074244608 1209025712 12.860 100 34 66 0 1705656480 487587584 9043184
06:19:18 26821 8192000000 10601693712 11116593152 1182206128 12.611 100 30 60 0 1726715680 535659168 8850384
06:20:46 46328 8192000000 10417942032 10939367424 1128348112 12.106 96 26 50 0 1535252768 400168416 6736240
06:22:34 37710 8192000000 10166063776 10745020416 1152402944 12.332 100 24 47 1 1294799120 136892960 5503216
06:24:14 50687 8192000000 10114313952 10560585728 1126273424 12.086 97 24 46 0 1216084256 84543728 5267104
06:26:05 27933 8192000000 10158854576 10644025344 1155951856 12.365 100 32 62 0 1246613360 84681152 5980352
06:27:44 70036 8192000000 10111248528 10556747776 1163059568 12.432 100 25 50 0 1202101680 33883232 5158880
06:29:55 155039 8192000000 10107337872 10590642176 1099243616 11.830 95 9 18 0 1132940032 32048640 1647776
06:33:31 132274 8192000000 10062504112 10480787456 1053439248 11.394 91 10 20 0 1116314352 61108096 1767008
06:37:02 66501 8192000000 10125741488 10589011968 1135812960 12.176 100 40 68 1 1219299104 75505872 7980272
06:39:10 18288 8192000000 10115161840 10567770112 1142469936 12.239 100 75 140 1 1215630256 58092096 15068224
06:40:32 91778 8192000000 10094369360 10537394176 1055953616 11.418 93 25 51 0 1102141808 40581744 5606448
06:43:04 31828 8192000000 10104206832 10548830208 1122971360 12.055 100 26 52 1 1199365024 70504112 5889552
06:44:40 23555 8192000000 10120303696 10556522496 1113286704 11.964 98 53 100 0 1215936096 91423248 11226144
06:46:22 109060 8192000000 10086970848 10558586880 1069562240 11.548 95 22 43 0 1140318480 66272032 4484208
06:49:13 36371 8192000000 10114803056 10588061696 1129186256 12.114 100 14 28 0 1207453504 75130032 3137216
06:50:49 64063 8192000000 10123166512 10607702016 1133593216 12.155 100 14 28 0 1243751504 106848816 3309472
06:52:59 61693 8192000000 10148452880 10611683328 1149299680 12.303 100 19 38 0 1233769872 80141120 4329072
06:55:03 26331 8192000000 10104387840 10565955584 1122457920 12.050 98 43 84 0 1208962176 77665152 8839104
06:56:30 73604 8192000000 10099935088 10549628928 1125883888 12.083 98 20 40 0 1212682032 82849584 3948560
06:58:52 77446 8192000000 10088324656 10539307008 1119942416 12.026 97 19 39 1 1178479840 54678272 3859152
07:01:09 85880 8192000000 10100846960 10548035584 1110270064 11.935 96 11 22 0 1145374624 33026016 2078544
07:03:39 25295 8192000000 10143550176 10580434944 1136068880 12.179 98 48 94 0 1197778640 52697008 9012752
07:05:05 119902 8192000000 10115655872 10568544256 1117319984 12.002 97 21 42 0 1211519664 89498752 4700928
07:08:26 62060 8192000000 10098890800 10559782912 1138298992 12.200 100 30 59 0 1183630448 39029888 6301568
07:10:30 67039 8192000000 10107372816 10570395648 1138553296 12.202 99 18 34 0 1202525872 59685984 4286592
07:12:43 54816 8192000000 10097066112 10547453952 1128938464 12.111 98 17 34 0 1186091808 53413344 3740000
07:14:47 82797 8192000000 10118438912 10601988096 1123713328 12.062 99 11 22 0 1209955920 83676880 2565712
07:17:10 20823 8192000000 10046359744 10506858496 1116341856 11.992 99 23 40 1 1150339264 29630480 4366928
07:18:31 75097 8192000000 10068212816 10523009024 1108741696 11.921 98 38 77 1 1162500096 45599616 8158784
07:21:08 68092 8192000000 10100113824 10545156096 1080507952 11.652 97 20 40 0 1154726624 69775824 4442848
07:23:21 57127 8192000000 10062023408 10502733824 1069239728 11.545 96 21 38 1 1127287024 54421952 3625344
07:25:19 37315 8192000000 10063369472 10502774784 1097368480 11.813 98 39 75 1 1156192016 50783568 8039968
07:26:57 13181 8192000000 10115460800 10543042560 1117549360 12.004 100 54 108 0 1206902864 78612688 10740816
07:28:12 43823 8192000000 10097763600 10567884800 1104107024 11.877 98 31 62 0 1185061040 74452352 6501664
07:30:07 3123 8192000000 10095788160 10508836864 1119378656 12.021 100 88 172 0 1190830080 53896816 17554608
07:31:11 25699 8192000000 10052396896 10509201408 1053070192 11.390 94 23 44 0 1103380032 45919264 4390576
07:32:38 56340 8192000000 10109903856 10551287808 1088068160 11.724 97 20 40 0 1164029888 71725888 4235840
07:34:34 26279 8192000000 10113636608 10586222592 1110598352 11.938 99 23 44 0 1188304640 72608240 5098048
07:36:03 52808 8192000000 10086602224 10533048320 1104675488 11.882 98 18 36 0 1146953040 38566448 3711104
07:37:58 78392 8192000000 10067263824 10548744192 1105568800 11.890 98 25 50 0 1172902096 61996736 5336560
07:40:26 51180 8192000000 10099946368 10573557760 1138972688 12.206 100 23 44 0 1216624848 72705456 4946704
07:42:18 104480 8192000000 10095207856 10575572992 1107388416 11.908 97 16 31 0 1181500816 70413808 3698592
07:45:09 73083 8192000000 9727806000 10160295936 824933344 9.148 73 6 12 0 879214624 53010112 1271168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-03-2023 07:48:07 to 15-03-2023 07:48:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2023 07:48:24 to 15-03-2023 07:48:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224186
MON$OLDEST_ACTIVE               224187
MON$OLDEST_SNAPSHOT             224187
MON$NEXT_TRANSACTION            224187
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-30 07:30:08.6030 GMT
MON$PAGES                       2107776
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Mar 15 07:46:26 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Mar 15 07:46:28 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Mar 15 07:46:29 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

15.03.23 07:48:28. There are 3 logs in /var/tmp/logs-oltp40 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
2023-03-15 07:48:30 No severe PSQL-related problems occured
15.03.23 07:48:32. End of report.