Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.02.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 14919780 kB
MemAvailable 30385068 kB
Buffers 0 kB
Cached 16757240 kB
SwapCached 3348 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-02-15 07:47:10
DTS_END                         2023-02-15 07:47:10.9480
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/36236
TRN_ID                          225070
ATT_ID                          622
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: 8930.67 26617 1071681
customer order: creation 175.86 469 21103
customer order: refuse 15.07 476 1808
order to supplier: creation 47.05 1313 5646
order to supplier: removal 3.20 196563 384
invoice (draft): creation 46.50 3272 5580
invoice (draft): removal 48.18 1136 5781
invoice accept: apply 42.00 26989 5040
invoice accept: cancel 43.40 1444 5208
customer reserve: creation 3905.09 254 468611
customer reserve: removal 4308.63 114 517035
realization accept: apply 85.53 311 10263
realization accept: cancel 78.35 185 9402
payment from customer: creation 60.14 191 7217
payment from customer: removal 5.63 136 676
payment to supplier: creation 49.65 212 5958
payment to supplier: removal 3.37 119 404
service: total inventory turnovers 4.15 4231 498
service: total monetary turnovers 7.78 494 934
service: aggregate perf. data 1.08 13812 129
service: refresh index statistics 0.03 280611 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-02-2023 07:48:16 to 15-02-2023 07:48:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11174
TEST_TIME 2 7342
TEST_TIME 3 7370
TEST_TIME 4 6508
TEST_TIME 5 6815
TEST_TIME 6 6337
TEST_TIME 7 6063
TEST_TIME 8 6060
TEST_TIME 9 5921
TEST_TIME 10 5794
TEST_TIME 11 5941
TEST_TIME 12 6472
TEST_TIME 13 6606
TEST_TIME 14 6707
TEST_TIME 15 6885
TEST_TIME 16 7323
TEST_TIME 17 7404
TEST_TIME 18 7522
TEST_TIME 19 7398
TEST_TIME 20 7347
TEST_TIME 21 7320
TEST_TIME 22 7348
TEST_TIME 23 7404
TEST_TIME 24 7329
TEST_TIME 25 7424
TEST_TIME 26 7755
TEST_TIME 27 7842
TEST_TIME 28 8179
TEST_TIME 29 8418
TEST_TIME 30 8533
TEST_TIME 31 8668
TEST_TIME 32 9098
TEST_TIME 33 9102
TEST_TIME 34 8924
TEST_TIME 35 9458
TEST_TIME 36 10185
TEST_TIME 37 10413
TEST_TIME 38 10387
TEST_TIME 39 10337
TEST_TIME 40 10373
TEST_TIME 41 10201
TEST_TIME 42 10162
TEST_TIME 43 10119
TEST_TIME 44 10042
TEST_TIME 45 10047
TEST_TIME 46 10009
TEST_TIME 47 9812
TEST_TIME 48 9690
TEST_TIME 49 9721
TEST_TIME 50 9585
TEST_TIME 51 9552
TEST_TIME 52 9512
TEST_TIME 53 9479
TEST_TIME 54 9493
TEST_TIME 55 9570
TEST_TIME 56 9504
TEST_TIME 57 9460
TEST_TIME 58 9404
TEST_TIME 59 9306
TEST_TIME 60 9241
TEST_TIME 61 9136
TEST_TIME 62 9071
TEST_TIME 63 9304
TEST_TIME 64 9277
TEST_TIME 65 9246
TEST_TIME 66 9262
TEST_TIME 67 9241
TEST_TIME 68 9227
TEST_TIME 69 9219
TEST_TIME 70 9274
TEST_TIME 71 9217
TEST_TIME 72 9303
TEST_TIME 73 9412
TEST_TIME 74 9505
TEST_TIME 75 9588
TEST_TIME 76 9517
TEST_TIME 77 9525
TEST_TIME 78 9465
TEST_TIME 79 9441
TEST_TIME 80 9370
TEST_TIME 81 9352
TEST_TIME 82 9320
TEST_TIME 83 9262
TEST_TIME 84 9271
TEST_TIME 85 9241
TEST_TIME 86 9215
TEST_TIME 87 9177
TEST_TIME 88 9128
TEST_TIME 89 9171
TEST_TIME 90 9124
TEST_TIME 91 9103
TEST_TIME 92 9087
TEST_TIME 93 9107
TEST_TIME 94 9072
TEST_TIME 95 9183
TEST_TIME 96 9155
TEST_TIME 97 9172
TEST_TIME 98 9174
TEST_TIME 99 9192
TEST_TIME 100 9181
TEST_TIME 101 9197
TEST_TIME 102 9186
TEST_TIME 103 9291
TEST_TIME 104 9249
TEST_TIME 105 9177
TEST_TIME 106 9139
TEST_TIME 107 9129
TEST_TIME 108 9163
TEST_TIME 109 9140
TEST_TIME 110 9119
TEST_TIME 111 9076
TEST_TIME 112 9038
TEST_TIME 113 9014
TEST_TIME 114 8984
TEST_TIME 115 9010
TEST_TIME 116 8996
TEST_TIME 117 9000
TEST_TIME 118 9028
TEST_TIME 119 9028
TEST_TIME 120 8970

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-02-2023 07:48:20 to 15-02-2023 07:48:24.

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 1502454 1502454 0 0.00 0 27273 14 0 0
doc_list_biud 1502438 1502438 0 0.00 0 34205 46 0 0
sp_add_invoice_to_stock 5146 5040 106 2.05 77 248380 26989 0 106
sp_cancel_adding_invoice 5208 5208 0 0.00 6 132210 1444 0 0
sp_cancel_client_order 1808 1808 0 0.00 5 13707 476 0 0
sp_cancel_customer_reserve 517035 517035 0 0.00 1 28411 115 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 5193 136 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 2572 119 0 0
sp_cancel_supplier_invoice 5786 5781 5 0.08 3 19012 1136 0 5
sp_cancel_supplier_order 384 384 0 0.00 25 1407905 196564 0 0
sp_cancel_write_off 9402 9402 0 0.00 2 21898 185 0 0
sp_client_order 21103 21103 0 0.00 5 29880 469 0 0
sp_customer_reserve 468611 468611 0 0.00 0 147954 254 0 0
sp_fill_shopping_cart 32323 32323 0 0.00 1 11888 83 0 0
sp_fill_shopping_cart_clo_res 468560 468560 0 0.00 0 17212 19 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 29288 263 0 0
sp_kill_cost_storno 15690 15690 0 0.00 0 27259 83 0 0
sp_kill_qstorno_ret_qs2qd 1049637 1049637 0 0.00 0 28404 50 0 0
sp_kill_qty_storno 554865 554865 0 0.00 0 34204 115 0 0
sp_lock_selected_doc 21071 21071 0 0.00 0 13027 10 0 0
sp_make_cost_storno 28422 28422 0 0.00 0 11144 84 0 0
sp_multiply_rows_for_pdistr 28422 28422 0 0.00 0 26377 42 0 0
sp_multiply_rows_for_qdistr 21103 21103 0 0.00 0 21899 181 0 0
sp_pay_from_customer 7217 7217 0 0.00 5 26494 191 0 0
sp_pay_to_supplier 5958 5958 0 0.00 7 36679 213 0 0
sp_payment_common 13173 13173 0 0.00 2 36622 51 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 13700 368 0 0
sp_qd_handle_on_invoice_upd_sts 10192 10192 0 0.00 0 34204 604 0 0
sp_qd_handle_on_reserve_upd_sts 10263 10263 0 0.00 0 28658 79 0 0
sp_reserve_write_off 10263 10263 0 0.00 8 28782 311 0 0
sp_supplier_invoice 5580 5580 0 0.00 7 85896 3272 0 0
sp_supplier_order 5646 5646 0 0.00 7 29530 1313 0 0
srv_aggregate_perf_data 129 129 0 0.00 2801 132416 13812 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 775 171037 32413 0 0
srv_find_qd_qs_mism 1021544 1021544 0 0.00 0 15449 11 0 0
srv_make_invnt_saldo 498 498 0 0.00 54 90786 4231 0 0
srv_make_money_saldo 934 934 0 0.00 1 34281 495 0 0
srv_recalc_idx_stat 4 4 0 0.00 69469 449369 280611 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5046 5046 0 0.00 0 12757 1022 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 7159 436 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 3542 34 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 4521 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 849 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 2049 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 957 42 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 110866 8301 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 752 17 0 0
t$perf-norm:sp_client_order 21103 21103 0 0.00 0 8902 39 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 1214 15 0 0
t$perf-norm:sp_pay_from_customer 7217 7217 0 0.00 0 9533 20 0 0
t$perf-norm:sp_pay_to_supplier 5958 5958 0 0.00 0 8424 23 0 0
t$perf-norm:sp_reserve_write_off 10263 10263 0 0.00 0 4695 24 0 0
t$perf-norm:sp_supplier_invoice 5580 5580 0 0.00 0 8336 35 0 0
t$perf-norm:sp_supplier_order 5647 5647 0 0.00 0 6149 33 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 285 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 211 8 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 2466 23 0 0
t$perf-norm:srv_make_money_saldo 934 934 0 0.00 0 477 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 191 54 0 0
v_add_invoice_to_stock 10176 9964 212 2.08 0 5374 29 0 212
v_all_customers 38535 38535 0 0.00 0 66 4 0 0
v_all_suppliers 31386 31386 0 0.00 0 92 4 0 0
v_all_wares 42202 42202 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 5264 26 0 0
v_cancel_client_order 3616 3616 0 0.00 0 5032 14 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2218 13 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 1 1470 13 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1149 13 0 0
v_cancel_supplier_invoice 850 840 10 1.17 0 3332 29 0 10
v_cancel_supplier_order 768 768 0 0.00 0 2616 16 0 0
v_cancel_write_off 2012 2012 0 0.00 0 2046 24 0 0
v_max_id_clo_ord 5645 5645 0 0.00 0 3741 6 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 4587 17 0 0
v_max_id_ord_sup 5575 5575 0 0.00 0 4420 8 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 137 8 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 4440 11 0 0
v_min_id_clo_ord 5645 5645 0 0.00 0 3599 9 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 8314 24 0 0
v_min_id_ord_sup 5575 5575 0 0.00 0 1797 8 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 36608 24 0 0
v_min_non_paid_realizn 7216 7216 0 0.00 0 6795 20 0 0
v_our_firm 10497 10497 0 0.00 0 63 5 0 0
v_reserve_write_off 20522 20522 0 0.00 0 4246 12 0 0
x_lock_depdocs_on_canc_invoice 10569 10569 0 0.00 0 18867 149 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 11432 437 0 0
x_make_qstorno_1000_1200 5646 5646 0 0.00 2 29517 1138 0 0
x_make_qstorno_1200_2000 5580 5580 0 0.00 3 77389 3045 0 0
x_make_qstorno_2100_3300 468611 468611 0 0.00 0 147951 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2023 07:48:25 to 15-02-2023 07:48:29.

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:17:14 2 8192000000 8799625728 8811241472 4840208 0.059 2 1 2 0 4939632 63648 35776
05:18:15 22794 8192000000 9710231680 10008461312 791362064 8.809 100 88 176 1 821597312 8011952 22223296
05:19:46 15036 8192000000 9774729936 10084507648 848931552 9.389 100 88 174 0 880188912 10702640 20554720
05:21:06 14765 8192000000 9799531200 10116222976 865858896 9.559 100 83 164 0 901748096 17307776 18581424
05:22:26 8708 8192000000 9868336720 10200567808 911764976 10.015 100 80 160 0 971685776 42372656 17548144
05:23:40 5584 8192000000 9910396688 10255814656 953467696 10.425 100 83 166 0 1026990944 55507200 18016048
05:24:49 2592 8192000000 9965934608 10317381632 998220896 10.861 100 96 190 0 1087366944 69589040 19557008
05:25:55 1673 8192000000 10008961904 10371883008 1019946656 11.071 100 81 160 1 1122281792 85988304 16346832
05:26:59 888 8192000000 10013780160 10379247616 1041131168 11.276 100 93 182 1 1140119440 81119216 17869056
05:28:02 1115 8192000000 10015028144 10388021248 1056575840 11.424 100 95 183 1 1132401264 58438928 17386496
05:29:04 1051 8192000000 10057540368 10438242304 1066867232 11.522 100 96 185 0 1179070224 93173520 19029472
05:30:06 12520 8192000000 10015480928 10402603008 1074178704 11.592 100 62 117 1 1153551920 68273248 11099968
05:31:20 1096 8192000000 10041227568 10430238720 1082566480 11.672 100 82 144 3 1161384528 63789152 15028896
05:32:22 3796 8192000000 10048224224 10444656640 1090494672 11.747 100 75 139 3 1165738976 61552864 13691440
05:33:27 1541 8192000000 10054692672 10443128832 1099589136 11.834 100 76 146 1 1174387808 59928208 14870464
05:34:29 1038 8192000000 10069537888 10458882048 1099248592 11.831 100 97 185 1 1189966800 71712176 19006032
05:35:31 1246 8192000000 10072145648 10463322112 1094683680 11.787 99 85 167 1 1194344000 83123712 16536608
05:36:34 1170 8192000000 10078602512 10467074048 1105930800 11.894 100 97 185 0 1200297312 74618640 19747872
05:37:35 1100 8192000000 10080544272 10470686720 1097185760 11.811 100 98 189 1 1203909968 87555408 19168800
05:38:37 989 8192000000 10061413760 10451836928 1095617952 11.796 100 87 162 2 1180547616 68532176 16397488
05:39:39 1053 8192000000 10021416432 10405920768 1082515680 11.671 100 94 170 0 1143795520 42471568 18808272
05:40:41 6525 8192000000 10022768320 10411253760 1058042304 11.438 98 69 133 1 1123072576 52421760 12608512
05:41:48 6278 8192000000 9977728704 10362191872 1025326944 11.123 98 57 112 2 1090593792 54076128 11190720
05:42:56 889 8192000000 10010122432 10387435520 1042983392 11.293 100 92 178 1 1132586176 71011424 18591360
05:43:58 1312 8192000000 10030116736 10406035456 1044220032 11.305 100 96 176 0 1154113376 91556816 18336528
05:45:00 1076 8192000000 9973854864 10344189952 1030680784 11.175 100 94 181 3 1098242784 49226624 18335376
05:46:02 1078 8192000000 9993343856 10364841984 1042072672 11.285 100 84 150 2 1111385904 53229856 16083376
05:47:04 1096 8192000000 10045692368 10423422976 1051107120 11.371 100 90 172 0 1160903936 92723648 17073168
05:48:18 3511 8192000000 10025390576 10406555648 1075572016 11.605 100 83 153 4 1150595104 60869104 14153984
05:49:31 6895 8192000000 10067450368 10445225984 1089080784 11.734 100 87 163 2 1183916512 78987280 15848448
05:50:40 1126 8192000000 10067069040 10450436096 1099909696 11.837 100 92 179 2 1189012480 67410992 21691792
05:51:46 1113 8192000000 10113522352 10497822720 1115756752 11.987 100 89 161 4 1228432784 97380688 15295344
05:52:50 17506 8192000000 10144385104 10542305280 1128359936 12.106 100 64 121 3 1263456016 121427968 13668112
05:54:11 2337 8192000000 10160236784 10558631936 1138412624 12.201 100 67 129 1 1277276400 124814864 14048912
05:55:18 4997 8192000000 10219454464 10627465216 1147816816 12.289 100 76 141 0 1337668864 174260944 15591104
05:56:34 1551 8192000000 10256660288 10656325632 1163922640 12.440 100 95 182 1 1371167232 186128560 21116032
05:57:41 4758 8192000000 10286382432 10692325376 1165654784 12.456 100 70 140 0 1395544160 214095360 15794016
05:58:50 1110 8192000000 10298753168 10721693696 1174975024 12.543 100 98 187 3 1413841344 217114256 21752064
05:59:54 933 8192000000 10316980320 10737528832 1179245248 12.583 100 94 186 0 1432139424 231117520 21776656
06:01:05 1389 8192000000 10328579488 10748502016 1183266128 12.621 100 94 175 0 1436021296 232582592 20172576
06:02:10 3691 8192000000 10331980784 10769207296 1185708384 12.643 100 86 172 0 1446102352 240071840 20322128
06:03:17 1451 8192000000 10338756960 10758803456 1187646240 12.661 100 94 177 1 1449011568 240759968 20605360
06:04:23 1390 8192000000 10350776464 10788102144 1202045184 12.795 100 90 178 1 1456928448 234320496 20562768
06:05:29 6884 8192000000 10367301920 10784284672 1205671904 12.829 100 75 149 0 1469394016 246655504 17066608
06:06:40 978 8192000000 10406998064 10811281408 1208704896 12.857 100 95 188 1 1510768880 280979680 21084304
06:07:45 1213 8192000000 10427796240 10846203904 1201956368 12.794 100 88 170 0 1535632560 313414816 20261376
06:08:52 1237 8192000000 10451676560 10868518912 1205044592 12.823 100 97 192 1 1556477056 328698448 22734016
06:09:57 1447 8192000000 10507160480 10914709504 1200101136 12.777 100 94 184 2 1611177520 388485760 22590624
06:11:03 1404 8192000000 10512026928 10936094720 1203212032 12.806 100 94 186 0 1617417216 391010960 23194224
06:12:08 875 8192000000 10517142544 10950791168 1189821280 12.682 100 94 186 0 1622132144 410016304 22294560
06:13:11 787 8192000000 10549606048 10996584448 1190152352 12.685 100 95 186 1 1654206320 440629696 23424272
06:14:15 821 8192000000 10534392704 10997030912 1195897488 12.738 100 77 153 0 1640688384 424418672 20372224
06:15:20 768 8192000000 10548738208 11004018688 1183557712 12.623 100 90 177 0 1651161280 445257808 22345760
06:16:23 7285 8192000000 10533994624 10989776896 1190979120 12.692 100 57 115 0 1648091696 442392032 14720544
06:17:47 7755 8192000000 10497304800 10961895424 1193734384 12.718 100 46 90 0 1601705568 396319488 11651696
06:18:57 3694 8192000000 10503950416 10972569600 1191561504 12.698 100 48 91 2 1605896272 402568224 11766544
06:20:18 14219 8192000000 10461721328 10948268032 1186273152 12.649 100 34 68 0 1569205760 374064384 8868224
06:21:35 15467 8192000000 10498810144 10947096576 1167343728 12.472 100 42 84 0 1596173792 418065088 10764976
06:22:54 33006 8192000000 10280503344 10810572800 1144513056 12.258 100 23 44 0 1419698912 269010272 6175584
06:24:29 77421 8192000000 10082636864 10543542272 1143808512 12.251 100 8 16 0 1208794976 63283296 1703168
06:26:47 99484 8192000000 10064832688 10546360320 1096822416 11.807 96 21 36 3 1144610176 43706752 4081008
06:29:29 70010 8192000000 10083834736 10508394496 1074581344 11.596 95 39 75 0 1116344480 32547552 9215584
06:31:46 55055 8192000000 10062574544 10526932992 1098743984 11.826 98 21 40 2 1142205136 38734432 4726720
06:33:41 97015 8192000000 10087354928 10526408704 1125465536 12.079 100 25 48 0 1178431408 48109952 4855920
06:36:19 171037 8192000000 10095962784 10536177664 1076070976 11.610 95 27 52 0 1126470384 45245312 5154096
06:40:12 24085 8192000000 10118535536 10564849664 1137060160 12.188 100 46 88 0 1231618336 84770944 9787232
06:41:39 11060 8192000000 10090032752 10525646848 1131895424 12.139 100 56 105 3 1189508816 46607184 11006208
06:42:52 79909 8192000000 10062404064 10526912512 1128413056 12.106 99 18 34 1 1180257296 48293408 3550832
06:46:04 17811 8192000000 10114228064 10570059776 1146264768 12.274 100 14 26 1 1206951584 58041872 2644944
06:47:22 87161 8192000000 10164954176 10631057408 1143161904 12.245 99 26 52 0 1252535792 103954320 5419568
06:49:53 60284 8192000000 10078931136 10631196672 1129576416 12.117 99 13 26 0 1288328128 155718240 3033472
06:51:54 35470 8192000000 10102616736 10551984128 1152318816 12.331 100 26 49 1 1212451616 54743936 5388864
06:53:32 17907 8192000000 10141660288 10581630976 1140770160 12.223 98 38 76 0 1215951248 66277280 8903808
06:54:56 18690 8192000000 10183184112 10622894080 1115426000 11.984 96 38 73 0 1224914352 101040800 8447552
06:56:18 35519 8192000000 10220434688 10662703104 1152634544 12.334 100 39 76 0 1320748432 159734896 8378992
06:58:00 48511 8192000000 10231981072 10673344512 1129174544 12.114 97 28 54 0 1287179376 151843472 6161360
06:59:51 41242 8192000000 10150689984 10606071808 1138967344 12.206 99 31 62 0 1250372880 104330144 7075392
07:01:47 31280 8192000000 10137003728 10588573696 1137871600 12.196 100 27 54 1 1229330800 85990032 5469168
07:03:31 44217 8192000000 10133717200 10579234816 1108512368 11.918 98 42 80 1 1233316224 116176656 8627200
07:05:15 90935 8192000000 10053170816 10490253312 1122368928 12.049 100 29 55 1 1173248064 44651360 6227776
07:08:16 77839 8192000000 10094523392 10565652480 1097794176 11.817 96 32 64 0 1173152768 68733616 6624976
07:11:13 71677 8192000000 10107114272 10595934208 1128121088 12.104 99 22 44 0 1221666896 88448528 5097280
07:13:32 53905 8192000000 10078355392 10560520192 1130039376 12.122 99 13 27 0 1179513232 46894208 2579648
07:15:52 91724 8192000000 10055917216 10564775936 1096300512 11.803 97 9 18 0 1177166224 78685152 2180560
07:18:23 42142 8192000000 10122424480 10563231744 1132134080 12.141 100 36 71 0 1187504864 48536816 6833968
07:20:15 56422 8192000000 10131140096 10594496512 1134122816 12.160 100 27 54 1 1226308464 86134560 6051088
07:22:12 41241 8192000000 10078832768 10544386048 1122679312 12.052 99 38 72 1 1174913088 45339264 6894512
07:23:56 30170 8192000000 10094851728 10550243328 1122807904 12.054 99 46 92 1 1204115456 72219280 9088272
07:25:27 19099 8192000000 10120920320 10567438336 1129913728 12.121 99 49 98 0 1198841520 59432048 9495744
07:26:48 23067 8192000000 10095771968 10528235520 1115181120 11.981 98 46 89 1 1168145968 44103680 8861168
07:28:12 31536 8192000000 10120513568 10565771264 1141758736 12.232 100 28 56 1 1213179712 65764288 5656688
07:29:44 31761 8192000000 10144546224 10623623168 1135379248 12.172 99 50 93 1 1232803216 87510976 9912992
07:32:14 125469 8192000000 10129599120 10636697600 1112106608 11.952 99 29 58 0 1229145296 110853840 6184848
07:35:22 15386 8192000000 10060762496 10611404800 1121170672 12.038 100 6 11 0 1169523472 47411568 941232
07:36:49 49363 8192000000 10054860944 10508115968 1117595056 12.004 99 13 26 0 1145458272 25003872 2859344
07:38:40 77974 8192000000 10071920256 10552766464 1111030624 11.942 98 14 28 0 1179726976 66058176 2638176
07:40:59 56479 8192000000 10072342640 10514251776 1101607792 11.853 97 11 17 0 1158941216 55550624 1782800
07:42:56 14389 8192000000 10083206688 10514796544 1123963456 12.064 100 40 69 4 1176117392 43930240 8223696
07:44:13 34068 8192000000 10093497088 10544615424 1115125008 11.981 98 34 66 0 1185101920 63250768 6726144
07:45:48 24001 8192000000 10098966752 10535063552 1123110960 12.056 99 25 47 0 1183663520 56037072 4515488

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-02-2023 07:48:31 to 15-02-2023 07:48:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

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

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          223244
MON$OLDEST_ACTIVE               225383
MON$OLDEST_SNAPSHOT             225383
MON$NEXT_TRANSACTION            225383
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                       2074272
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2902 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2902 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2902 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 Feb 15 07:47:15 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Feb 15 07:47:19 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Feb 15 07:47:19 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Wed Feb 15 07:47:19 2023
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Wed Feb 15 07:47:21 2023
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

15.02.23 07:48:52. 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-02-15 07:48:54 No severe PSQL-related problems occured
15.02.23 07:48:56. End of report.