Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2023 07: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 14909892 kB
MemAvailable 30450584 kB
Buffers 0 kB
Cached 16789480 kB
SwapCached 3308 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-17 07:37:24
DTS_END                         2023-02-17 07:37:24.0170
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/38688
TRN_ID                          226522
ATT_ID                          567
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: 9201.46 21742 1104175
customer order: creation 178.17 449 21380
customer order: refuse 15.43 367 1851
order to supplier: creation 47.64 1228 5717
order to supplier: removal 3.71 142786 445
invoice (draft): creation 47.09 2812 5651
invoice (draft): removal 52.35 1006 6282
invoice accept: apply 42.15 23159 5058
invoice accept: cancel 46.92 1256 5630
customer reserve: creation 3918.76 220 470251
customer reserve: removal 4543.13 91 545175
realization accept: apply 85.14 248 10217
realization accept: cancel 87.14 138 10457
payment from customer: creation 61.59 150 7391
payment from customer: removal 6.20 125 744
payment to supplier: creation 49.64 173 5957
payment to supplier: removal 3.45 122 414
service: total inventory turnovers 4.22 3837 506
service: total monetary turnovers 7.78 336 933
service: aggregate perf. data 0.93 15582 111
service: refresh index statistics 0.04 240746 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2023 07:38:48 to 17-02-2023 07:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7353
TEST_TIME 2 6173
TEST_TIME 3 6732
TEST_TIME 4 5958
TEST_TIME 5 5963
TEST_TIME 6 5820
TEST_TIME 7 5870
TEST_TIME 8 5963
TEST_TIME 9 5941
TEST_TIME 10 6121
TEST_TIME 11 6115
TEST_TIME 12 6290
TEST_TIME 13 6256
TEST_TIME 14 6399
TEST_TIME 15 6779
TEST_TIME 16 6671
TEST_TIME 17 6914
TEST_TIME 18 7216
TEST_TIME 19 7827
TEST_TIME 20 8539
TEST_TIME 21 8795
TEST_TIME 22 8567
TEST_TIME 23 8642
TEST_TIME 24 8715
TEST_TIME 25 8730
TEST_TIME 26 8546
TEST_TIME 27 8575
TEST_TIME 28 9489
TEST_TIME 29 9954
TEST_TIME 30 10555
TEST_TIME 31 10486
TEST_TIME 32 10947
TEST_TIME 33 10786
TEST_TIME 34 10811
TEST_TIME 35 10851
TEST_TIME 36 10757
TEST_TIME 37 10680
TEST_TIME 38 10565
TEST_TIME 39 10533
TEST_TIME 40 10470
TEST_TIME 41 10423
TEST_TIME 42 10508
TEST_TIME 43 10498
TEST_TIME 44 10366
TEST_TIME 45 10494
TEST_TIME 46 10587
TEST_TIME 47 10519
TEST_TIME 48 10536
TEST_TIME 49 10325
TEST_TIME 50 10212
TEST_TIME 51 10100
TEST_TIME 52 10145
TEST_TIME 53 10013
TEST_TIME 54 9997
TEST_TIME 55 9978
TEST_TIME 56 9964
TEST_TIME 57 9927
TEST_TIME 58 9828
TEST_TIME 59 9807
TEST_TIME 60 9791
TEST_TIME 61 9785
TEST_TIME 62 9695
TEST_TIME 63 9760
TEST_TIME 64 9721
TEST_TIME 65 9694
TEST_TIME 66 9635
TEST_TIME 67 9526
TEST_TIME 68 9557
TEST_TIME 69 9510
TEST_TIME 70 9478
TEST_TIME 71 9421
TEST_TIME 72 9435
TEST_TIME 73 9531
TEST_TIME 74 9467
TEST_TIME 75 9508
TEST_TIME 76 9565
TEST_TIME 77 9587
TEST_TIME 78 9657
TEST_TIME 79 9666
TEST_TIME 80 9670
TEST_TIME 81 9676
TEST_TIME 82 9763
TEST_TIME 83 9711
TEST_TIME 84 9622
TEST_TIME 85 9554
TEST_TIME 86 9591
TEST_TIME 87 9563
TEST_TIME 88 9504
TEST_TIME 89 9457
TEST_TIME 90 9426
TEST_TIME 91 9392
TEST_TIME 92 9371
TEST_TIME 93 9321
TEST_TIME 94 9374
TEST_TIME 95 9363
TEST_TIME 96 9325
TEST_TIME 97 9313
TEST_TIME 98 9348
TEST_TIME 99 9355
TEST_TIME 100 9388
TEST_TIME 101 9435
TEST_TIME 102 9485
TEST_TIME 103 9538
TEST_TIME 104 9501
TEST_TIME 105 9525
TEST_TIME 106 9498
TEST_TIME 107 9413
TEST_TIME 108 9367
TEST_TIME 109 9396
TEST_TIME 110 9328
TEST_TIME 111 9322
TEST_TIME 112 9296
TEST_TIME 113 9295
TEST_TIME 114 9263
TEST_TIME 115 9253
TEST_TIME 116 9238
TEST_TIME 117 9272
TEST_TIME 118 9261
TEST_TIME 119 9269
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-02-2023 07:38:52 to 17-02-2023 07:38:55.

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 1538350 1538350 0 0.00 0 23142 12 0 0
doc_list_biud 1538335 1538335 0 0.00 0 31294 38 0 0
sp_add_invoice_to_stock 5227 5058 169 3.23 79 224285 23159 0 169
sp_cancel_adding_invoice 5630 5630 0 0.00 6 98210 1257 0 0
sp_cancel_client_order 1851 1851 0 0.00 6 14993 367 0 0
sp_cancel_customer_reserve 545175 545175 0 0.00 1 31308 91 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 3 5751 125 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 5035 123 0 0
sp_cancel_supplier_invoice 6299 6282 17 0.26 2 18545 1006 0 17
sp_cancel_supplier_order 445 445 0 0.00 14 1458430 142786 0 0
sp_cancel_write_off 10457 10457 0 0.00 2 9116 138 0 0
sp_client_order 21380 21380 0 0.00 5 19046 449 0 0
sp_customer_reserve 470251 470251 0 0.00 0 140990 220 0 0
sp_fill_shopping_cart 32736 32736 0 0.00 2 15055 74 0 0
sp_fill_shopping_cart_clo_res 470214 470214 0 0.00 0 16702 18 0 0
sp_get_clo_for_invoice 5015 5015 0 0.00 0 28422 304 0 0
sp_kill_cost_storno 17245 17245 0 0.00 0 11523 68 0 0
sp_kill_qstorno_ret_qs2qd 1107534 1107534 0 0.00 0 31292 41 0 0
sp_kill_qty_storno 585069 585069 0 0.00 0 31294 94 0 0
sp_lock_selected_doc 21234 21234 0 0.00 0 9428 8 0 0
sp_make_cost_storno 28580 28580 0 0.00 0 23126 78 0 0
sp_multiply_rows_for_pdistr 28578 28578 0 0.00 0 9775 34 0 0
sp_multiply_rows_for_qdistr 21380 21380 0 0.00 0 10273 195 0 0
sp_pay_from_customer 7391 7391 0 0.00 5 11816 150 0 0
sp_pay_to_supplier 5957 5957 0 0.00 6 20329 174 0 0
sp_payment_common 13348 13348 0 0.00 2 8242 44 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 14984 297 0 0
sp_qd_handle_on_invoice_upd_sts 10644 10644 0 0.00 0 16657 487 0 0
sp_qd_handle_on_reserve_upd_sts 10215 10215 0 0.00 0 14268 52 0 0
sp_reserve_write_off 10217 10217 0 0.00 9 14448 248 0 0
sp_supplier_invoice 5651 5651 0 0.00 8 58726 2813 0 0
sp_supplier_order 5717 5717 0 0.00 7 24601 1228 0 0
srv_aggregate_perf_data 111 111 0 0.00 2500 151302 15582 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 738 166012 36638 0 0
srv_find_qd_qs_mism 1054467 1054467 0 0.00 0 16508 9 0 0
srv_make_invnt_saldo 506 506 0 0.00 64 70948 3838 0 0
srv_make_money_saldo 933 933 0 0.00 1 9572 336 0 0
srv_recalc_idx_stat 5 5 0 0.00 26799 383614 240746 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 0 9136 774 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 7124 410 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 7827 25 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1708 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 2358 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 4107 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 6956 44 0 0
t$perf-norm:sp_cancel_supplier_order 445 445 0 0.00 0 96535 6363 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 1063 17 0 0
t$perf-norm:sp_client_order 21381 21381 0 0.00 0 7068 31 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 1780 13 0 0
t$perf-norm:sp_pay_from_customer 7391 7391 0 0.00 0 5569 17 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 3152 18 0 0
t$perf-norm:sp_reserve_write_off 10217 10217 0 0.00 0 6426 22 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 4893 25 0 0
t$perf-norm:sp_supplier_order 5717 5717 0 0.00 0 2529 27 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 620 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 39 4 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 740 12 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 1325 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 184 42 0 0
v_add_invoice_to_stock 10360 10022 338 3.26 0 7632 25 0 338
v_all_customers 39140 39140 0 0.00 0 68 3 0 0
v_all_suppliers 31554 31554 0 0.00 0 90 4 0 0
v_all_wares 42754 42754 0 0.00 0 61 3 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 536 13 0 0
v_cancel_client_order 3702 3702 0 0.00 0 1446 9 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 2097 11 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2335 12 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2607 16 0 0
v_cancel_supplier_invoice 924 890 34 3.67 0 1326 26 0 34
v_cancel_supplier_order 890 890 0 0.00 0 503 9 0 0
v_cancel_write_off 1960 1960 0 0.00 0 1199 21 0 0
v_max_id_clo_ord 5715 5715 0 0.00 0 684 5 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 4123 14 0 0
v_max_id_ord_sup 5644 5644 0 0.00 0 5487 7 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 1083 8 0 0
v_max_non_paid_realizn 3939 3939 0 0.00 0 5551 9 0 0
v_min_id_clo_ord 5714 5714 0 0.00 0 3456 10 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 4317 15 0 0
v_min_id_ord_sup 5644 5644 0 0.00 0 15016 10 0 0
v_min_non_paid_invoice 5957 5957 0 0.00 0 8221 19 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 7132 19 0 0
v_our_firm 10518 10518 0 0.00 0 79 4 0 0
v_reserve_write_off 20430 20430 0 0.00 0 2533 10 0 0
x_lock_depdocs_on_canc_invoice 11467 11467 0 0.00 0 38850 133 0 0
x_lock_depdocs_on_canc_sup_ord 445 445 0 0.00 0 21159 330 0 0
x_make_qstorno_1000_1200 5717 5717 0 0.00 3 24585 1072 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 3 58532 2619 0 0
x_make_qstorno_2100_3300 470251 470251 0 0.00 0 140988 192 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 07:38:56 to 17-02-2023 07:39:00.

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:07:28 919 8192000000 8920228608 8975982592 18751696 0.228 5 2 3 0 18901552 80096 69760
05:08:29 27127 8192000000 9723185440 10022678528 803761376 8.934 100 90 180 0 833299072 7709136 21828560
05:09:59 14559 8192000000 9760337744 10066239488 834638560 9.246 100 87 173 0 868074320 12977008 20458752
05:11:34 13772 8192000000 9807502048 10127499264 873000176 9.630 100 84 168 0 917938416 25848128 19090112
05:12:57 8140 8192000000 9863952976 10198880256 918332832 10.080 100 79 156 0 981848528 46402288 17113408
05:14:08 1800 8192000000 9910677760 10252726272 954803344 10.438 100 91 175 0 1025909920 52151904 18954672
05:15:14 1548 8192000000 9954671680 10303037440 984397664 10.727 100 90 180 0 1073730800 70965760 18367376
05:16:19 1910 8192000000 9994050800 10355412992 1014170560 11.016 100 92 180 0 1112583632 79977600 18435472
05:17:22 844 8192000000 10007289056 10372423680 1031123216 11.179 100 94 185 0 1125068288 74736000 19209072
05:18:24 860 8192000000 10029433920 10400010240 1058349744 11.441 100 94 178 0 1147723760 71003408 18370608
05:19:26 948 8192000000 10027249344 10402574336 1062394144 11.479 100 89 176 0 1147607888 66441520 18772224
05:20:28 3071 8192000000 10027368432 10402201600 1072455536 11.576 100 85 158 1 1153126400 65203200 15467664
05:21:32 855 8192000000 10047458960 10438000640 1076624736 11.615 100 81 160 1 1169995808 77442336 15928736
05:22:33 1251 8192000000 10038448160 10427899904 1081356032 11.660 100 98 191 1 1162491280 61678656 19456592
05:23:36 8453 8192000000 10027444096 10424348672 1082710960 11.673 100 51 100 0 1160374736 68329008 9334768
05:24:45 1494 8192000000 10052923152 10440822784 1087380000 11.718 100 85 166 5 1165165344 61511488 16273856
05:25:47 1314 8192000000 10034527984 10421649408 1090189456 11.744 100 83 149 4 1160123904 54543392 15391056
05:26:49 1952 8192000000 10061449152 10455601152 1087504320 11.719 100 81 158 1 1186455520 83567376 15383824
05:27:56 1001 8192000000 10052235104 10444238848 1097989040 11.819 100 92 173 1 1178148768 62150160 18009568
05:28:58 1059 8192000000 10038355568 10422046720 1086547568 11.710 100 96 180 1 1159619696 54580080 18492048
05:30:00 1079 8192000000 10040967216 10430087168 1074535920 11.595 99 87 166 0 1164656240 71982272 18138048
05:31:02 1093 8192000000 10018664528 10406891520 1079632368 11.644 100 78 147 2 1141399424 46728016 15039040
05:32:04 4291 8192000000 9996769072 10379509760 1051857216 11.378 99 72 136 0 1113411568 47061520 14492832
05:33:09 1118 8192000000 9971410896 10345500672 1037598384 11.242 99 85 158 1 1094311808 40644016 16069408
05:34:11 980 8192000000 9995828432 10372235264 1043012960 11.294 100 92 175 0 1117493984 56616672 17864352
05:35:13 1745 8192000000 9995170112 10377338880 1038213216 11.247 99 73 141 1 1114544736 61385392 14946128
05:36:16 1008 8192000000 9987046160 10365198336 1038647440 11.252 100 94 170 4 1109861808 52717744 18496624
05:37:18 919 8192000000 10003857376 10383331328 1060365104 11.460 100 91 168 1 1121741856 43799088 17577664
05:38:24 14641 8192000000 10002641072 10394406912 1057371040 11.431 100 66 126 2 1127730704 57086336 13273328
05:39:45 10707 8192000000 10038880992 10434093056 1086457888 11.709 100 62 122 0 1157705424 58327264 12920272
05:41:07 5895 8192000000 10091255264 10473738240 1090752320 11.750 100 77 150 0 1204908640 98996544 15159776
05:42:19 5271 8192000000 10131935360 10512613376 1098505328 11.823 100 89 168 0 1245290752 128894496 17890928
05:43:27 1034 8192000000 10172673920 10561814528 1122721312 12.053 100 74 142 1 1283894048 146603456 14569280
05:44:32 3995 8192000000 10191265840 10582396928 1135960304 12.178 100 82 159 0 1311148112 157324928 17862880
05:45:38 1831 8192000000 10235074560 10631462912 1147801088 12.289 100 89 170 1 1353920320 187259456 18859776
05:46:43 1266 8192000000 10288025024 10684510208 1157680016 12.382 100 84 159 1 1398338896 223132384 17526496
05:47:48 1491 8192000000 10311382704 10710691840 1171291776 12.509 100 89 168 3 1420863536 230410704 19161056
05:48:52 817 8192000000 10367445840 10762645504 1174133856 12.535 100 100 196 3 1479418464 282856208 22428400
05:49:56 1375 8192000000 10383898080 10779684864 1173893472 12.533 100 92 176 3 1492280560 298264608 20122480
05:51:02 1111 8192000000 10433882256 10843484160 1182458320 12.613 100 93 185 1 1548305360 344252208 21594832
05:52:05 2217 8192000000 10464911984 10898268160 1182476368 12.613 100 89 178 0 1577573520 374194080 20903072
05:53:10 1850 8192000000 10466383280 10873876480 1173900704 12.533 100 86 153 2 1574625440 383044496 17680240
05:54:18 1041 8192000000 10502955344 10947461120 1171660368 12.512 100 97 191 1 1612628832 417738336 23230128
05:55:21 723 8192000000 10452735632 10928689152 1166692080 12.466 100 98 188 2 1560192880 371012704 22488096
05:56:24 3819 8192000000 10449737056 10897969152 1168061984 12.479 100 76 152 0 1555729664 369217072 18450608
05:57:35 1203 8192000000 10426662416 10889912320 1180570096 12.596 100 75 148 1 1532023968 333756784 17697088
05:58:42 9243 8192000000 10378621152 10842128384 1159878864 12.402 99 48 94 3 1474760576 303040448 11841264
06:00:25 3650 8192000000 10409965760 10865491968 1168332848 12.481 100 64 121 2 1522420416 339356032 14731536
06:01:35 4329 8192000000 10468020304 10887155712 1175578080 12.549 100 60 120 1 1573548864 383882400 14088384
06:02:43 20152 8192000000 10468387040 10902917120 1138413312 12.201 98 33 63 0 1564074736 417231248 8430176
06:04:13 11780 8192000000 10487148480 10964180992 1146200368 12.274 100 49 97 0 1614103808 455715216 12188224
06:05:34 11504 8192000000 10302170896 10811305984 1143303520 12.247 100 38 76 0 1455959024 303073104 9582400
06:06:47 18626 8192000000 10238236320 10742616064 1138613104 12.202 99 38 75 1 1340548992 193152528 8783360
06:08:07 51196 8192000000 10157459744 10659995648 1096730176 11.807 96 18 35 0 1272082592 171356464 3995952
06:10:09 25741 8192000000 10107653360 10547777536 1138160544 12.198 100 31 62 0 1196684272 52237696 6286032
06:11:36 71278 8192000000 10054035376 10533453824 1087175648 11.716 95 11 22 0 1153131168 63848304 2107216
06:13:56 127163 8192000000 10054618368 10486972416 1049732960 11.358 93 17 34 0 1067846784 14396848 3716976
06:17:11 59825 8192000000 10115334656 10552639488 1140641904 12.222 100 14 27 0 1177923488 34741072 2540512
06:19:11 62616 8192000000 10082248352 10585735168 1114277104 11.973 97 17 34 0 1223543328 105038976 4227248
06:21:20 55604 8192000000 10143879792 10607104000 1148132000 12.292 100 17 32 0 1240920672 89233632 3555040
06:23:19 77653 8192000000 10075301536 10507616256 1092307296 11.765 96 10 20 0 1150359344 56188368 1863680
06:25:41 139302 8192000000 10114588752 10562359296 1133004544 12.150 99 17 32 0 1166639888 30727952 2907392
06:29:02 126625 8192000000 10107459680 10536071168 1079211440 11.640 94 51 95 3 1181205760 92350864 9643456
06:32:11 33721 8192000000 10118498624 10575646720 1118873648 12.016 98 28 54 0 1198063920 73390256 5800016
06:34:15 35489 8192000000 10115691184 10568445952 1128780032 12.110 100 48 92 0 1219964720 80849536 10335152
06:35:57 105503 8192000000 10120340912 10602131456 1112424640 11.955 98 21 41 1 1238961664 121716944 4820080
06:38:43 166012 8192000000 10080053808 10591051776 1113644496 11.967 98 11 22 0 1192342752 76061600 2636656
06:43:08 59798 8192000000 10064855664 10514534400 1103948752 11.875 97 6 12 0 1152408896 47782304 677840
06:45:11 16934 8192000000 10060873936 10499747840 1112687456 11.958 98 34 68 0 1157436432 38215744 6533232
06:46:45 66304 8192000000 10073051968 10520305664 1073891664 11.589 95 13 26 0 1120914512 44621200 2401648
06:48:51 19880 8192000000 10143944624 10589442048 1134500896 12.164 100 42 81 0 1220232208 77398352 8332960
06:50:19 32576 8192000000 10083702720 10535567360 1096967328 11.809 97 21 42 0 1177881776 77068096 3846352
06:51:57 26516 8192000000 10152288048 10595905536 1136419120 12.182 100 38 75 1 1254189712 109906336 7864256
06:53:27 38870 8192000000 10128341440 10590339072 1119485296 12.022 98 22 40 0 1220967328 96780944 4701088
06:55:09 31321 8192000000 10098548144 10568110080 1110108784 11.933 98 26 52 0 1207058512 91069840 5879888
06:56:48 3038 8192000000 10094099488 10511937536 1121051136 12.037 100 61 112 1 1189919664 57347696 11520832
06:57:56 18419 8192000000 10105831648 10539376640 1101356560 11.851 98 39 78 0 1172338032 63362192 7619280
06:59:48 33138 8192000000 10068815600 10511237120 1116055008 11.990 99 28 56 0 1165166992 42811040 6300944
07:01:21 99599 8192000000 10111327632 10554667008 1087095904 11.715 96 37 70 1 1185400832 91136832 7168096
07:04:10 60444 8192000000 10062067328 10536169472 1118742656 12.015 100 15 29 1 1164605024 42755088 3107280
07:06:10 61102 8192000000 10074464304 10546647040 1104618160 11.881 98 16 30 1 1171891696 63715392 3558144
07:08:13 46860 8192000000 10079632304 10526265344 1116962688 11.998 99 15 28 1 1173936880 53746256 3227936
07:10:04 28080 8192000000 10101573056 10540752896 1076282960 11.612 95 27 50 3 1146079184 63952528 5843696
07:11:33 32042 8192000000 10103466928 10554261504 1115272816 11.982 99 35 63 0 1173293584 51124144 6896624
07:13:05 23241 8192000000 10155875744 10592169984 1148193584 12.293 100 35 70 0 1254634720 99583296 6857840
07:14:30 25255 8192000000 10209302144 10644004864 1149619344 12.306 100 44 88 1 1297749440 138499408 9630688
07:15:58 27166 8192000000 10151723616 10619760640 1121523312 12.041 98 32 64 0 1242491120 114254256 6713552
07:17:29 30010 8192000000 10164197600 10606972928 1138133216 12.198 100 28 56 0 1285811936 141377824 6300896
07:19:15 19306 8192000000 10190290688 10616152064 1133433072 12.154 100 57 107 1 1274078688 130060144 10585472
07:20:34 39617 8192000000 10073571360 10507177984 1108414832 11.917 98 21 42 1 1144669104 31997696 4256576
07:22:20 71029 8192000000 10075616032 10583670784 1114068608 11.971 98 22 44 0 1182634256 64267904 4297744
07:24:33 49852 8192000000 10093935376 10539835392 1138443200 12.201 100 40 79 0 1190649424 44443120 7763104
07:26:25 79356 8192000000 10100176640 10584379392 1143896176 12.252 100 14 28 0 1192842048 45719776 3226096
07:29:45 73462 8192000000 10070977216 10555047936 1087304336 11.717 96 11 22 1 1153835664 64193184 2338144
07:32:01 54421 8192000000 10086235424 10555400192 1115319632 11.983 99 19 38 0 1191816336 72388320 4108384
07:33:56 31171 8192000000 10105282848 10566676480 1096132976 11.801 97 42 82 0 1166134928 61406752 8595200
07:35:31 51639 8192000000 10132470928 10587975680 1129825568 12.120 99 27 54 0 1205132928 69769440 5537920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-02-2023 07:39:02 to 17-02-2023 07:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 17-02-2023 07:39:19 to 17-02-2023 07:39:22.

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          223548
MON$OLDEST_ACTIVE               226746
MON$OLDEST_SNAPSHOT             226746
MON$NEXT_TRANSACTION            226746
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                       2076256
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             783
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 ---
:122: 
:123: mscs-test-161.budzdorov.ru    Fri Feb 17 07:37:34 2023
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Fri Feb 17 07:37:38 2023
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Fri Feb 17 07:37:38 2023
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:133: 
--- end of diff output ---

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

17.02.23 07:39:23. 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-17 07:39:25 No severe PSQL-related problems occured
17.02.23 07:39:27. End of report.