Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2022 22:39
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 15626784 kB
MemAvailable 30606064 kB
Buffers 0 kB
Cached 16119784 kB
SwapCached 2056 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-30 22:38:27
DTS_END                         2022-09-30 22:38:27.8240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54918
TRN_ID                          245440
ATT_ID                          647
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: 9212.29 23363 1105475
customer order: creation 175.31 359 21037
customer order: refuse 16.13 334 1936
order to supplier: creation 46.38 1228 5565
order to supplier: removal 3.18 106836 381
invoice (draft): creation 47.58 3387 5710
invoice (draft): removal 50.58 811 6070
invoice accept: apply 42.16 17658 5059
invoice accept: cancel 45.26 804 5431
customer reserve: creation 3946.48 173 473577
customer reserve: removal 4539.33 60 544719
realization accept: apply 86.07 156 10328
realization accept: cancel 80.35 102 9642
payment from customer: creation 60.91 104 7309
payment from customer: removal 5.69 68 683
payment to supplier: creation 51.08 119 6130
payment to supplier: removal 3.48 52 417
service: total inventory turnovers 4.65 1073 558
service: total monetary turnovers 6.72 46 806
service: aggregate perf. data 0.94 18467 113
service: refresh index statistics 0.03 315431 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2022 22:40:00 to 30-09-2022 22:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3157
TEST_TIME 2 4668
TEST_TIME 3 5034
TEST_TIME 4 5171
TEST_TIME 5 4956
TEST_TIME 6 4822
TEST_TIME 7 4793
TEST_TIME 8 5052
TEST_TIME 9 4980
TEST_TIME 10 5226
TEST_TIME 11 5828
TEST_TIME 12 5991
TEST_TIME 13 6281
TEST_TIME 14 6448
TEST_TIME 15 6789
TEST_TIME 16 6927
TEST_TIME 17 7181
TEST_TIME 18 7269
TEST_TIME 19 7723
TEST_TIME 20 8024
TEST_TIME 21 8214
TEST_TIME 22 8413
TEST_TIME 23 8942
TEST_TIME 24 9163
TEST_TIME 25 9343
TEST_TIME 26 9569
TEST_TIME 27 9922
TEST_TIME 28 10489
TEST_TIME 29 10988
TEST_TIME 30 10873
TEST_TIME 31 10859
TEST_TIME 32 10600
TEST_TIME 33 10452
TEST_TIME 34 10499
TEST_TIME 35 10336
TEST_TIME 36 10234
TEST_TIME 37 10084
TEST_TIME 38 10036
TEST_TIME 39 9918
TEST_TIME 40 9781
TEST_TIME 41 9698
TEST_TIME 42 9617
TEST_TIME 43 9591
TEST_TIME 44 9594
TEST_TIME 45 9612
TEST_TIME 46 9476
TEST_TIME 47 9417
TEST_TIME 48 9344
TEST_TIME 49 9291
TEST_TIME 50 9371
TEST_TIME 51 9349
TEST_TIME 52 9383
TEST_TIME 53 9329
TEST_TIME 54 9325
TEST_TIME 55 9327
TEST_TIME 56 9355
TEST_TIME 57 9352
TEST_TIME 58 9357
TEST_TIME 59 9384
TEST_TIME 60 9461
TEST_TIME 61 9442
TEST_TIME 62 9509
TEST_TIME 63 9544
TEST_TIME 64 9593
TEST_TIME 65 9595
TEST_TIME 66 9523
TEST_TIME 67 9518
TEST_TIME 68 9487
TEST_TIME 69 9452
TEST_TIME 70 9425
TEST_TIME 71 9433
TEST_TIME 72 9390
TEST_TIME 73 9427
TEST_TIME 74 9379
TEST_TIME 75 9354
TEST_TIME 76 9367
TEST_TIME 77 9343
TEST_TIME 78 9345
TEST_TIME 79 9348
TEST_TIME 80 9351
TEST_TIME 81 9326
TEST_TIME 82 9407
TEST_TIME 83 9398
TEST_TIME 84 9439
TEST_TIME 85 9415
TEST_TIME 86 9489
TEST_TIME 87 9528
TEST_TIME 88 9549
TEST_TIME 89 9615
TEST_TIME 90 9608
TEST_TIME 91 9551
TEST_TIME 92 9492
TEST_TIME 93 9502
TEST_TIME 94 9464
TEST_TIME 95 9429
TEST_TIME 96 9419
TEST_TIME 97 9378
TEST_TIME 98 9333
TEST_TIME 99 9307
TEST_TIME 100 9410
TEST_TIME 101 9444
TEST_TIME 102 9428
TEST_TIME 103 9388
TEST_TIME 104 9468
TEST_TIME 105 9449
TEST_TIME 106 9506
TEST_TIME 107 9470
TEST_TIME 108 9440
TEST_TIME 109 9437
TEST_TIME 110 9446
TEST_TIME 111 9419
TEST_TIME 112 9354
TEST_TIME 113 9341
TEST_TIME 114 9297
TEST_TIME 115 9242
TEST_TIME 116 9240
TEST_TIME 117 9246
TEST_TIME 118 9209
TEST_TIME 119 9211
TEST_TIME 120 9215

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2022 22:40:03 to 30-09-2022 22:40:07.

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 1542722 1542722 0 0.00 0 19512 6 0 0
doc_list_biud 1542722 1542722 0 0.00 0 61229 27 0 0
sp_add_invoice_to_stock 5147 5059 88 1.70 44 162756 17658 0 88
sp_cancel_adding_invoice 5431 5431 0 0.00 5 77316 804 0 0
sp_cancel_client_order 1936 1936 0 0.00 4 11223 335 0 0
sp_cancel_customer_reserve 544719 544719 0 0.00 0 61231 60 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 3690 69 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 2344 53 0 0
sp_cancel_supplier_invoice 6075 6070 5 0.08 1 24635 811 0 5
sp_cancel_supplier_order 381 381 0 0.00 5 990604 106836 0 0
sp_cancel_write_off 9642 9642 0 0.00 1 11629 102 0 0
sp_client_order 21037 21037 0 0.00 3 30460 359 0 0
sp_customer_reserve 473577 473577 0 0.00 0 68341 174 0 0
sp_fill_shopping_cart 32306 32306 0 0.00 1 20393 38 0 0
sp_fill_shopping_cart_clo_res 473573 473573 0 0.00 0 13385 15 0 0
sp_get_clo_for_invoice 5053 5053 0 0.00 0 40382 287 0 0
sp_kill_cost_storno 16173 16173 0 0.00 0 11590 49 0 0
sp_kill_qstorno_ret_qs2qd 1105531 1105531 0 0.00 0 61227 33 0 0
sp_kill_qty_storno 583557 583557 0 0.00 0 61229 70 0 0
sp_lock_selected_doc 21399 21399 0 0.00 0 7370 5 0 0
sp_make_cost_storno 28817 28817 0 0.00 0 19511 49 0 0
sp_multiply_rows_for_pdistr 28817 28817 0 0.00 0 10996 23 0 0
sp_multiply_rows_for_qdistr 21037 21037 0 0.00 0 13133 153 0 0
sp_pay_from_customer 7309 7309 0 0.00 4 9217 105 0 0
sp_pay_to_supplier 6130 6130 0 0.00 4 14867 120 0 0
sp_payment_common 13439 13439 0 0.00 1 11996 23 0 0
sp_qd_handle_on_cancel_clo 1936 1936 0 0.00 0 11216 289 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 31357 306 0 0
sp_qd_handle_on_reserve_upd_sts 10328 10328 0 0.00 0 11552 52 0 0
sp_reserve_write_off 10328 10328 0 0.00 6 19550 156 0 0
sp_supplier_invoice 5710 5710 0 0.00 5 127461 3388 0 0
sp_supplier_order 5565 5565 0 0.00 4 70060 1229 0 0
srv_aggregate_perf_data 113 113 0 0.00 2760 301779 18467 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2034 161578 46770 0 0
srv_find_qd_qs_mism 1055874 1055874 0 0.00 0 9034 6 0 0
srv_make_invnt_saldo 558 558 0 0.00 32 38641 1073 0 0
srv_make_money_saldo 806 806 0 0.00 1 14103 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 145541 415371 315431 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 5059 5059 0 0.00 0 12580 158 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 9582 83 0 0
t$perf-norm:sp_cancel_client_order 1936 1936 0 0.00 0 12784 24 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 1048 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 484 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 2751 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 455 455 0 0.00 0 1179 14 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 29225 1651 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 4802 13 0 0
t$perf-norm:sp_client_order 21037 21037 0 0.00 0 8129 15 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 1666 5 0 0
t$perf-norm:sp_pay_from_customer 7309 7309 0 0.00 0 8171 8 0 0
t$perf-norm:sp_pay_to_supplier 6130 6130 0 0.00 0 7019 12 0 0
t$perf-norm:sp_reserve_write_off 10328 10328 0 0.00 0 8661 12 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 7734 22 0 0
t$perf-norm:sp_supplier_order 5565 5565 0 0.00 0 4648 13 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 546 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 984 21 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 873 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10274 10098 176 1.71 0 5822 19 0 176
v_all_customers 38444 38444 0 0.00 0 29 0 0 0
v_all_suppliers 31610 31610 0 0.00 0 17 1 0 0
v_all_wares 42074 42074 0 0.00 0 155 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3223 13 0 0
v_cancel_client_order 3872 3872 0 0.00 0 5910 11 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1865 7 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 3498 11 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 763 4 0 0
v_cancel_supplier_invoice 920 910 10 1.08 0 3488 18 0 10
v_cancel_supplier_order 762 762 0 0.00 0 291 4 0 0
v_cancel_write_off 2108 2108 0 0.00 0 928 12 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 437 1 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 1809 9 0 0
v_max_id_ord_sup 5706 5706 0 0.00 0 5354 5 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 2561 5 0 0
v_max_non_paid_realizn 3865 3865 0 0.00 0 6832 6 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 4335 5 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 5715 17 0 0
v_min_id_ord_sup 5706 5706 0 0.00 0 4538 8 0 0
v_min_non_paid_invoice 6130 6130 0 0.00 0 6901 14 0 0
v_min_non_paid_realizn 7309 7309 0 0.00 0 6666 16 0 0
v_our_firm 10518 10518 0 0.00 0 16 1 0 0
v_reserve_write_off 20656 20656 0 0.00 0 5810 8 0 0
x_lock_depdocs_on_canc_invoice 11046 11046 0 0.00 0 10339 114 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 6521 369 0 0
x_make_qstorno_1000_1200 5565 5565 0 0.00 1 70050 1161 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 2 120063 3247 0 0
x_make_qstorno_2100_3300 473577 473577 0 0.00 0 68335 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2022 22:40:08 to 30-09-2022 22:40:12.

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
20:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 24065 8192000000 9892839600 10199175168 966833296 10.556 100 88 176 0 996288736 5885104 23570336
20:11:02 16644 8192000000 9935621600 10252873728 1019739600 11.070 100 91 182 0 1051595424 7898304 23957520
20:12:29 13284 8192000000 9974243600 10305601536 1060234192 11.459 100 81 158 0 1089518688 9474656 19809840
20:14:00 6605 8192000000 10043475952 10387582976 1127038400 12.093 100 79 158 0 1156972816 10551696 19382720
20:15:11 4324 8192000000 10092037040 10451066880 1179919312 12.589 100 82 164 0 1210626960 11432320 19275328
20:16:22 3920 8192000000 10133693200 10506346496 1229078496 13.046 100 80 158 0 1258752176 12393104 17280576
20:17:27 1242 8192000000 10155694144 10530099200 1244510448 13.188 100 81 160 0 1277011408 14833712 17667248
20:18:33 1445 8192000000 10186775504 10571575296 1277533168 13.490 100 76 150 0 1306180304 12263776 16383360
20:19:37 1372 8192000000 10213119040 10597421056 1299708848 13.693 100 87 174 0 1331823680 12746064 19368768
20:20:40 47117 8192000000 10194848016 10604802048 1306601632 13.755 100 38 76 1 1323361328 7596688 9163008
20:22:31 3445 8192000000 10197388672 10613288960 1315245856 13.834 100 25 50 0 1326703776 5614384 5843536
20:23:35 9353 8192000000 10207345664 10625949696 1324052064 13.913 100 23 47 1 1335785296 6361760 5371472
20:24:46 7452 8192000000 10220593360 10639290368 1329862944 13.966 100 26 52 0 1346722096 11204480 5654672
20:25:58 56959 8192000000 10217584544 10645389312 1336109168 14.022 100 21 42 0 1345766016 4994352 4662496
20:27:55 3985 8192000000 10233795040 10655834112 1342171248 14.077 100 21 40 2 1356664048 9825680 4667120
20:29:03 9976 8192000000 10191521456 10598117376 1248769968 13.227 95 23 46 0 1258647312 4275440 5601904
20:30:14 28729 8192000000 10132776944 10530975744 1151857232 12.327 90 13 26 1 1159955840 4825728 3272880
20:31:43 12626 8192000000 10097750400 10493575168 1160981712 12.412 93 14 28 0 1169612400 5236208 3394480
20:32:57 6648 8192000000 10140799344 10544222208 1236920384 13.118 99 23 42 0 1247212688 5292928 4999376
20:34:09 3884 8192000000 10172030432 10577879040 1276608096 13.482 100 24 49 0 1287878768 5073264 6197408
20:35:13 51638 8192000000 10191669008 10599235584 1291187120 13.615 100 23 46 1 1302146896 5373248 5586528
20:37:10 7072 8192000000 10188070256 10605883392 1300740112 13.702 100 28 56 0 1313594496 5942832 6911552
20:38:17 10158 8192000000 10218375824 10634670080 1323068672 13.904 100 23 46 0 1338227856 9547968 5611216
20:39:35 5161 8192000000 10248435424 10656710656 1338825664 14.047 100 48 96 1 1358459088 9196944 10436480
20:40:49 13811 8192000000 10273629456 10684321792 1357546064 14.215 100 60 118 1 1382778096 12168912 13063120
20:42:17 51990 8192000000 10260989568 10690764800 1352453328 14.170 99 27 54 0 1371004064 11710176 6840560
20:44:23 25138 8192000000 10272184672 10697822208 1372097488 14.346 100 35 69 0 1392296304 11770512 8428304
20:45:54 9983 8192000000 10303138144 10724864000 1390201216 14.508 100 45 86 1 1416762512 15829440 10731856
20:47:07 19514 8192000000 10326685872 10751094784 1403830720 14.629 100 51 102 2 1433453168 16922080 12700368
20:48:30 5050 8192000000 10333654512 10755825664 1405117680 14.641 100 65 127 1 1444266112 23621232 15527200
20:49:42 4277 8192000000 10314481072 10731630592 1386110512 14.471 100 65 126 1 1425212256 23905472 15196272
20:50:52 3168 8192000000 10324555616 10743623680 1386017424 14.470 99 67 134 0 1430066304 27834544 16214336
20:52:02 2034 8192000000 10341963600 10751082496 1393399728 14.536 99 81 158 2 1442930992 31160304 18370960
20:53:07 7365 8192000000 10322386928 10739396608 1364743936 14.280 98 56 108 0 1412043920 33740672 13559312
20:54:40 9267 8192000000 10323554256 10745458688 1388521632 14.493 100 48 89 2 1435001760 34651728 11828400
20:55:52 3881 8192000000 10361675376 10780024832 1412325296 14.705 100 64 128 1 1470101152 40998096 16777760
20:57:00 2414 8192000000 10353748416 10764722176 1392659040 14.530 100 82 157 0 1453051728 41584192 18808496
20:58:07 9424 8192000000 10302304736 10722734080 1362729952 14.262 100 39 78 0 1414382288 40714336 10938000
20:59:22 4283 8192000000 10313596528 10730209280 1362286976 14.258 100 58 114 0 1421321456 44004752 15029728
21:00:29 8743 8192000000 10316152512 10734170112 1354351424 14.187 99 41 80 0 1408375072 43520512 10503136
21:01:40 13392 8192000000 10304384128 10730790912 1356775904 14.208 99 30 60 0 1407371520 41837920 8757696
21:03:03 15709 8192000000 10316110480 10736959488 1360668176 14.243 100 30 60 0 1411780176 42531824 8580176
21:04:21 11007 8192000000 10313697120 10742628352 1362731840 14.262 100 28 56 0 1421578448 50442688 8403920
21:05:53 27474 8192000000 10268151232 10699190272 1322531600 13.900 99 25 50 1 1371415376 41724096 7159680
21:07:22 78580 8192000000 10241989344 10671902720 1321262720 13.888 98 33 66 1 1342463248 13167008 8033520
21:09:44 22897 8192000000 10264015216 10713628672 1357402880 14.214 100 18 34 0 1379625904 18069440 4153584
21:11:09 49018 8192000000 10277375248 10715570176 1359752368 14.235 100 38 69 0 1378999776 12665376 6582032
21:13:00 54744 8192000000 10257753104 10705510400 1350763568 14.154 99 10 20 1 1358950336 6151728 2035040
21:14:54 24872 8192000000 10258842928 10701254656 1349770032 14.145 99 8 17 0 1357898944 6411072 1717840
21:16:30 78165 8192000000 10286482928 10734469120 1378762640 14.405 100 14 28 0 1388737168 7027952 2946576
21:18:57 115291 8192000000 10303786480 10767745024 1400541120 14.600 100 13 26 1 1418703552 15145712 3016720
21:21:52 58504 8192000000 10303746880 10755911680 1386267520 14.473 99 37 69 2 1406791936 12378800 8145616
21:23:59 59163 8192000000 10296901392 10751868928 1384130320 14.453 99 20 40 0 1399958016 10922784 4904912
21:26:00 18007 8192000000 10332572272 10773082112 1393617264 14.538 99 60 120 1 1420951472 12710000 14624208
21:27:21 80109 8192000000 10308272368 10777341952 1387499376 14.484 98 19 38 0 1409324000 17326048 4498576
21:29:51 35201 8192000000 10302652512 10769883136 1407358640 14.660 100 9 18 0 1416968368 7785296 1824432
21:31:26 44479 8192000000 10317495008 10775654400 1402386080 14.616 99 22 43 1 1417523328 10803952 4333296
21:33:20 62275 8192000000 10312574512 10764480512 1372514704 14.350 97 25 50 0 1389609248 11780848 5313696
21:35:24 51251 8192000000 10306441440 10761056256 1384575824 14.457 99 22 44 0 1399254448 9733552 4945072
21:37:20 35101 8192000000 10305236720 10754760704 1385660496 14.467 99 31 62 0 1403590304 10891616 7038192
21:38:56 51808 8192000000 10287126800 10731937792 1329877072 13.966 95 21 43 0 1342468320 7753808 4837440
21:40:49 144549 8192000000 10255946944 10702274560 1137403120 12.191 82 14 28 1 1152558512 12495424 2659968
21:44:15 51383 8192000000 10253979568 10681942016 1275328368 13.470 94 41 78 0 1295797088 11243280 9225440
21:46:07 84927 8192000000 10266903552 10704367616 1312343456 13.807 97 27 54 0 1328909984 9980096 6586432
21:48:49 21895 8192000000 10248816944 10682454016 1332733664 13.992 98 31 62 0 1350854320 11427360 6693296
21:50:12 38587 8192000000 10260994192 10712256512 1353797072 14.182 99 12 24 0 1368048400 11352128 2899200
21:51:50 54887 8192000000 10249464912 10707722240 1310513088 13.791 96 11 22 0 1326525264 13225040 2787136
21:53:46 78790 8192000000 10237275088 10700050432 1315912432 13.840 97 16 32 0 1326945280 7465024 3567824
21:56:05 51999 8192000000 10226916480 10676133888 1261090432 13.340 93 12 24 0 1271399872 7708224 2601216
21:58:16 73000 8192000000 10281174752 10730254336 1350852016 14.155 99 14 28 0 1363627616 9678768 3096832
22:00:29 35425 8192000000 10278150144 10724282368 1373422368 14.358 100 25 44 0 1385854000 7171232 5260400
22:02:06 83831 8192000000 10304523216 10755362816 1389686480 14.503 100 22 44 0 1412332784 17508864 5137440
22:04:31 146593 8192000000 10298900144 10762694656 1361873536 14.254 98 16 31 0 1386445136 21118800 3452800
22:08:15 161577 8192000000 10303542592 10761887744 1395257696 14.553 100 19 38 1 1417882144 18291152 4333296
22:12:00 14534 8192000000 10299953520 10767290368 1394728592 14.548 100 16 32 0 1412027488 13754976 3543920
22:13:43 33269 8192000000 10295505840 10762326016 1400009520 14.595 100 13 26 0 1412392400 9473344 2909536
22:15:36 60040 8192000000 10315114992 10773123072 1407036944 14.658 100 17 34 0 1427625520 16860144 3728432
22:17:43 87082 8192000000 10306892720 10782584832 1409738272 14.682 100 14 29 0 1431903264 18635472 3529520
22:20:11 49299 8192000000 10293705472 10753605632 1360177712 14.239 97 24 48 0 1377537152 11902544 5456896
22:22:11 22272 8192000000 10315154000 10768117760 1397177888 14.570 100 53 104 2 1424023456 14789360 12056208
22:23:33 44537 8192000000 10318699648 10769760256 1380371808 14.420 99 27 54 0 1404410016 18140400 5897808
22:25:21 76552 8192000000 10273880144 10717216768 1240041168 13.147 89 28 57 0 1255517088 9484272 5991648
22:27:41 91184 8192000000 10247386416 10691981312 1233121088 13.083 91 28 56 0 1251928688 12535344 6272256
22:30:14 111221 8192000000 10233918960 10677641216 1296696576 13.665 97 29 58 2 1313409104 10122544 6589984
22:33:07 87553 8192000000 10241358288 10704011264 1336212928 14.023 99 13 24 0 1354440272 15536320 2691024
22:35:37 57271 8192000000 10243538272 10696568832 1310747184 13.793 97 20 40 0 1328151824 12557616 4847024
22:37:34 58385 8192000000 9315671008 9617154048 456387920 5.277 34 5 10 0 467669584 10023824 1257840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2022 22:40:14 to 30-09-2022 22:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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:02, from 30-09-2022 22:40:30 to 30-09-2022 22:40:32.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246374
MON$OLDEST_ACTIVE               246375
MON$OLDEST_SNAPSHOT             246375
MON$NEXT_TRANSACTION            246375
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2024256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

30.09.22 22:40:33. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-09-30 22:40:36 No severe PSQL-related problems occured
30.09.22 22:40:37. End of report.