Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2022 01: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 15039952 kB
MemAvailable 30118068 kB
Buffers 0 kB
Cached 16691364 kB
SwapCached 3388 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-12-22 01:38:34
DTS_END                         2022-12-22 01:38:34.1120
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/49236
TRN_ID                          241837
ATT_ID                          687
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: 9309.67 23521 1117160
customer order: creation 167.57 341 20108
customer order: refuse 14.33 326 1719
order to supplier: creation 45.11 1207 5413
order to supplier: removal 3.70 80684 444
invoice (draft): creation 45.17 3557 5420
invoice (draft): removal 54.69 746 6563
invoice accept: apply 40.98 16683 4918
invoice accept: cancel 49.08 780 5890
customer reserve: creation 3792.21 164 455065
customer reserve: removal 4808.44 47 577013
realization accept: apply 81.58 174 9789
realization accept: cancel 80.83 97 9700
payment from customer: creation 57.99 96 6959
payment from customer: removal 5.67 74 680
payment to supplier: creation 47.78 124 5734
payment to supplier: removal 3.07 71 368
service: total inventory turnovers 4.78 974 574
service: total monetary turnovers 5.80 26 696
service: aggregate perf. data 0.86 20301 103
service: refresh index statistics 0.03 343940 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2022 01:40:16 to 22-12-2022 01:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1741
TEST_TIME 2 2488
TEST_TIME 3 3408
TEST_TIME 4 3621
TEST_TIME 5 3745
TEST_TIME 6 4414
TEST_TIME 7 4492
TEST_TIME 8 4664
TEST_TIME 9 4958
TEST_TIME 10 5794
TEST_TIME 11 5993
TEST_TIME 12 6360
TEST_TIME 13 6440
TEST_TIME 14 6496
TEST_TIME 15 7010
TEST_TIME 16 7568
TEST_TIME 17 7750
TEST_TIME 18 8140
TEST_TIME 19 8306
TEST_TIME 20 8443
TEST_TIME 21 9165
TEST_TIME 22 9262
TEST_TIME 23 9162
TEST_TIME 24 9087
TEST_TIME 25 9039
TEST_TIME 26 9045
TEST_TIME 27 9384
TEST_TIME 28 9735
TEST_TIME 29 9499
TEST_TIME 30 9465
TEST_TIME 31 9657
TEST_TIME 32 10127
TEST_TIME 33 10202
TEST_TIME 34 10120
TEST_TIME 35 10133
TEST_TIME 36 9911
TEST_TIME 37 9714
TEST_TIME 38 9773
TEST_TIME 39 9646
TEST_TIME 40 9603
TEST_TIME 41 9631
TEST_TIME 42 9531
TEST_TIME 43 9416
TEST_TIME 44 9375
TEST_TIME 45 9300
TEST_TIME 46 9391
TEST_TIME 47 9424
TEST_TIME 48 9355
TEST_TIME 49 9320
TEST_TIME 50 9227
TEST_TIME 51 9326
TEST_TIME 52 9167
TEST_TIME 53 9076
TEST_TIME 54 9094
TEST_TIME 55 9067
TEST_TIME 56 9158
TEST_TIME 57 9144
TEST_TIME 58 9139
TEST_TIME 59 9324
TEST_TIME 60 9291
TEST_TIME 61 9278
TEST_TIME 62 9357
TEST_TIME 63 9456
TEST_TIME 64 9439
TEST_TIME 65 9452
TEST_TIME 66 9494
TEST_TIME 67 9555
TEST_TIME 68 9626
TEST_TIME 69 9546
TEST_TIME 70 9449
TEST_TIME 71 9358
TEST_TIME 72 9490
TEST_TIME 73 9420
TEST_TIME 74 9407
TEST_TIME 75 9395
TEST_TIME 76 9362
TEST_TIME 77 9326
TEST_TIME 78 9281
TEST_TIME 79 9327
TEST_TIME 80 9307
TEST_TIME 81 9324
TEST_TIME 82 9291
TEST_TIME 83 9329
TEST_TIME 84 9346
TEST_TIME 85 9403
TEST_TIME 86 9395
TEST_TIME 87 9365
TEST_TIME 88 9360
TEST_TIME 89 9402
TEST_TIME 90 9480
TEST_TIME 91 9525
TEST_TIME 92 9565
TEST_TIME 93 9636
TEST_TIME 94 9593
TEST_TIME 95 9509
TEST_TIME 96 9461
TEST_TIME 97 9417
TEST_TIME 98 9483
TEST_TIME 99 9429
TEST_TIME 100 9421
TEST_TIME 101 9413
TEST_TIME 102 9484
TEST_TIME 103 9433
TEST_TIME 104 9513
TEST_TIME 105 9521
TEST_TIME 106 9480
TEST_TIME 107 9517
TEST_TIME 108 9484
TEST_TIME 109 9533
TEST_TIME 110 9525
TEST_TIME 111 9495
TEST_TIME 112 9432
TEST_TIME 113 9409
TEST_TIME 114 9417
TEST_TIME 115 9395
TEST_TIME 116 9408
TEST_TIME 117 9387
TEST_TIME 118 9364
TEST_TIME 119 9358
TEST_TIME 120 9311

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2022 01:40:20 to 22-12-2022 01:40:23.

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 1538765 1538765 0 0.00 0 14597 5 0 0
doc_list_biud 1538765 1538765 0 0.00 0 31600 23 0 0
sp_add_invoice_to_stock 5046 4918 128 2.53 43 172320 16684 0 128
sp_cancel_adding_invoice 5890 5890 0 0.00 3 71907 780 0 0
sp_cancel_client_order 1719 1719 0 0.00 4 10875 327 0 0
sp_cancel_customer_reserve 577013 577013 0 0.00 0 31603 47 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 4561 74 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 5266 71 0 0
sp_cancel_supplier_invoice 6573 6563 10 0.15 1 16817 747 0 10
sp_cancel_supplier_order 444 444 0 0.00 9 835226 80685 0 0
sp_cancel_write_off 9700 9700 0 0.00 1 9124 97 0 0
sp_client_order 20108 20108 0 0.00 3 30793 341 0 0
sp_customer_reserve 455065 455065 0 0.00 0 90652 165 0 0
sp_fill_shopping_cart 30931 30931 0 0.00 1 9713 38 0 0
sp_fill_shopping_cart_clo_res 455065 455065 0 0.00 0 16758 14 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 33263 318 0 0
sp_kill_cost_storno 16638 16638 0 0.00 0 6228 48 0 0
sp_kill_qstorno_ret_qs2qd 1170733 1170733 0 0.00 0 31597 26 0 0
sp_kill_qty_storno 616027 616027 0 0.00 0 31600 58 0 0
sp_lock_selected_doc 20364 20364 0 0.00 0 10529 7 0 0
sp_make_cost_storno 27391 27391 0 0.00 0 14566 54 0 0
sp_multiply_rows_for_pdistr 27391 27391 0 0.00 0 7766 23 0 0
sp_multiply_rows_for_qdistr 20108 20108 0 0.00 0 30062 160 0 0
sp_pay_from_customer 6959 6959 0 0.00 4 8856 97 0 0
sp_pay_to_supplier 5734 5734 0 0.00 5 9444 124 0 0
sp_payment_common 12693 12693 0 0.00 1 7393 24 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 10833 294 0 0
sp_qd_handle_on_invoice_upd_sts 10799 10799 0 0.00 0 25298 318 0 0
sp_qd_handle_on_reserve_upd_sts 9789 9789 0 0.00 0 22242 59 0 0
sp_reserve_write_off 9789 9789 0 0.00 6 22281 175 0 0
sp_supplier_invoice 5420 5420 0 0.00 5 89629 3557 0 0
sp_supplier_order 5413 5413 0 0.00 5 38748 1207 0 0
srv_aggregate_perf_data 103 103 0 0.00 2415 394124 20302 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1999 224067 54612 0 0
srv_find_qd_qs_mism 1070529 1070529 0 0.00 0 12312 4 0 0
srv_make_invnt_saldo 574 574 0 0.00 32 22529 974 0 0
srv_make_money_saldo 696 696 0 0.00 1 1656 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 245475 447259 343940 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4918 4918 0 0.00 0 23257 177 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 3160 77 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 4089 18 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1851 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 411 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 138 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 736 14 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 28951 1438 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 4921 11 0 0
t$perf-norm:sp_client_order 20108 20108 0 0.00 0 14682 16 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 3124 13 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 5425 11 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 12866 14 0 0
t$perf-norm:sp_reserve_write_off 9789 9789 0 0.00 0 7210 13 0 0
t$perf-norm:sp_supplier_invoice 5420 5420 0 0.00 0 14142 16 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 3601 12 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 105 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 4334 74 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2075 23 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 4848 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10074 9818 256 2.54 0 5219 16 0 256
v_all_customers 36748 36748 0 0.00 0 15 0 0 0
v_all_suppliers 30032 30032 0 0.00 0 95 1 0 0
v_all_wares 40216 40216 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 4766 13 0 0
v_cancel_client_order 3438 3438 0 0.00 0 5142 5 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3879 9 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1696 6 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 1043 6 0 0
v_cancel_supplier_invoice 832 812 20 2.40 0 1327 18 0 20
v_cancel_supplier_order 888 888 0 0.00 0 1894 6 0 0
v_cancel_write_off 1916 1916 0 0.00 0 694 12 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 1086 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1324 10 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 5749 4 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 4506 10 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 6514 8 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 3932 7 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 1333 4 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 4608 11 0 0
v_min_non_paid_invoice 5734 5734 0 0.00 0 7388 17 0 0
v_min_non_paid_realizn 6959 6959 0 0.00 0 5065 15 0 0
v_our_firm 10004 10004 0 0.00 0 15 1 0 0
v_reserve_write_off 19576 19576 0 0.00 0 4954 5 0 0
x_lock_depdocs_on_canc_invoice 12047 12047 0 0.00 0 27868 118 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 6807 290 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 1 38726 1137 0 0
x_make_qstorno_1200_2000 5420 5420 0 0.00 3 85013 3418 0 0
x_make_qstorno_2100_3300 455065 455065 0 0.00 0 90651 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2022 01:40:24 to 22-12-2022 01:40:28.

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
23:08:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:38 7945 8192000000 9882957536 10192556032 972524464 10.611 100 86 172 0 1000570880 5544928 22501488
23:10:49 21962 8192000000 9950576640 10271145984 1033301184 11.200 100 89 176 0 1064133600 7952064 22880352
23:12:18 19965 8192000000 9979405584 10307035136 1059233600 11.449 100 90 180 0 1091519360 9450224 22835536
23:13:52 6721 8192000000 10025792064 10369994752 1109281104 11.926 100 76 152 0 1138221392 11081360 17858928
23:15:03 7065 8192000000 10069408432 10429681664 1161637344 12.419 100 75 149 1 1190094336 11389712 17067280
23:16:13 4357 8192000000 10128225024 10494894080 1214782256 12.913 100 82 164 0 1245140112 11707296 18650560
23:17:21 2256 8192000000 10161732016 10537795584 1246817936 13.209 100 85 169 0 1279247696 13314512 19115248
23:18:25 1517 8192000000 10192373424 10570731520 1274110864 13.459 100 87 171 2 1305002288 12549776 18341648
23:19:29 1065 8192000000 10211748224 10601091072 1304452176 13.736 100 87 172 1 1336978464 14037200 18489088
23:20:31 3777 8192000000 10206202624 10609692672 1309925376 13.785 100 37 74 1 1324957216 6973120 8058720
23:21:38 4940 8192000000 10201975408 10619531264 1319601824 13.873 100 24 48 0 1331894208 6993680 5298704
23:22:43 17220 8192000000 10214459424 10632744960 1329236896 13.960 100 20 39 0 1339714160 6172288 4304976
23:24:01 56766 8192000000 10234880400 10653204480 1341612128 14.072 100 34 63 1 1354074656 5412192 7050336
23:25:58 5398 8192000000 10240097232 10664603648 1343054208 14.085 100 29 59 0 1356154688 5706816 7393664
23:27:03 8642 8192000000 10233746768 10651197440 1342048112 14.076 100 17 34 0 1351535360 5556224 3931024
23:28:12 13894 8192000000 10199610448 10620174336 1288631040 13.592 97 11 22 0 1295751504 4933680 2186784
23:29:27 49798 8192000000 10171706240 10575081472 1170084784 12.498 89 28 54 0 1182485744 6527312 5873648
23:31:18 3696 8192000000 10155411120 10557984768 1263132368 13.359 100 27 55 1 1274162896 5374144 5656384
23:32:23 11974 8192000000 10145869280 10537209856 1194285184 12.723 95 26 53 0 1205895360 5154624 6455552
23:33:37 31452 8192000000 10157177088 10570960896 1262736288 13.355 99 19 37 1 1272335456 4761408 4837760
23:35:25 6445 8192000000 10195683008 10603913216 1296743216 13.666 100 37 74 0 1311503216 6643968 8116032
23:36:37 8123 8192000000 10201432928 10617004032 1309857632 13.785 100 19 38 0 1319656688 5470640 4328416
23:37:46 9299 8192000000 10208145520 10627743744 1317625744 13.855 100 13 26 0 1325960432 4659536 3675152
23:38:56 48574 8192000000 10238036848 10654867456 1337786624 14.037 100 37 70 0 1352462400 5628752 9047024
23:41:03 9605 8192000000 10266718688 10683260928 1357149648 14.212 100 54 106 0 1380685184 11479280 12056256
23:42:27 58481 8192000000 10278121168 10708447232 1371020144 14.336 100 32 64 0 1390288048 11903664 7364240
23:44:30 5597 8192000000 10302773184 10724864000 1389264064 14.499 100 51 102 0 1414724208 13014144 12446000
23:45:45 25365 8192000000 10318424560 10736672768 1393006192 14.533 100 61 121 1 1425757824 18957664 13793968
23:47:21 12191 8192000000 10324358816 10744360960 1398108688 14.578 100 54 108 0 1432978864 22049232 12820944
23:48:39 7207 8192000000 10328394160 10741542912 1395657712 14.556 100 68 134 0 1433587552 22502464 15427376
23:50:00 1995 8192000000 10325438336 10735030272 1389001888 14.497 100 83 165 0 1434771152 25622080 20147184
23:51:08 8640 8192000000 10321913264 10741944320 1372018864 14.345 98 55 107 1 1411395344 25956240 13420240
23:52:24 5591 8192000000 10327770704 10741121024 1382196848 14.436 100 65 130 0 1429965552 31904208 15864496
23:53:37 6366 8192000000 10305978256 10724593664 1368160944 14.311 99 59 118 2 1410038848 27260832 14617072
23:54:46 11580 8192000000 10290079440 10719772672 1355233424 14.195 99 31 63 1 1387599424 24286496 8079504
23:56:03 15326 8192000000 10297432224 10720206848 1367637968 14.306 100 49 98 0 1409756000 29611984 12506048
23:57:24 30355 8192000000 10263243584 10678816768 1287030976 13.577 95 33 66 0 1320665952 24674288 8960688
23:58:58 10892 8192000000 10284041296 10707124224 1348297472 14.132 100 41 82 0 1390322176 31622032 10402672
00:00:15 11024 8192000000 10267385728 10695852032 1346284512 14.114 100 34 66 0 1377840880 23240656 8315712
00:01:31 38745 8192000000 10264550528 10689486848 1314351280 13.826 97 26 52 0 1339820304 19005504 6463520
00:03:40 11062 8192000000 10275551664 10705047552 1352028352 14.166 100 23 46 0 1388474368 29974544 6471472
00:04:55 8464 8192000000 10294519392 10711932928 1350027904 14.148 100 42 84 0 1392813888 32096416 10689568
00:06:08 10313 8192000000 10301186192 10727616512 1364570016 14.278 100 35 66 0 1405651456 32334448 8746992
00:07:32 49733 8192000000 10293308544 10709884928 1339898432 14.056 98 35 67 1 1376797424 28602192 8296800
00:09:24 42394 8192000000 10294116000 10735374336 1372473008 14.349 100 12 24 0 1404672768 28925104 3274656
00:11:08 88455 8192000000 10289367424 10723815424 1359928096 14.237 99 13 28 0 1381901776 19017968 2955712
00:13:37 224067 8192000000 10303687872 10750857216 1398433072 14.581 100 37 73 0 1419965488 13171792 8360624
00:18:23 45084 8192000000 10309880864 10768904192 1399180576 14.588 100 34 66 2 1425026112 17889744 7955792
00:20:09 70783 8192000000 10297103280 10749157376 1389682464 14.503 99 9 18 0 1403640608 11866944 2091200
00:22:21 125030 8192000000 10312405600 10775900160 1395864656 14.558 99 21 42 0 1425262640 24172112 5225872
00:25:26 63392 8192000000 10297450464 10763161600 1402543600 14.618 100 23 45 0 1419481632 11150560 5787472
00:27:37 29772 8192000000 10292659552 10761457664 1394399696 14.545 99 13 26 0 1410401936 12908880 3093360
00:29:08 60108 8192000000 10289762784 10768379904 1387324832 14.482 99 10 20 0 1405717344 15939248 2453264
00:31:17 24453 8192000000 10299485920 10762571776 1404761760 14.637 100 22 42 3 1417262656 6702640 5798256
00:33:04 66367 8192000000 10292435248 10752933888 1372995104 14.354 98 33 65 0 1389390320 9500416 6894800
00:35:10 35551 8192000000 10311825216 10769436672 1401968352 14.613 100 23 46 0 1418331056 11292592 5070112
00:36:48 75119 8192000000 10288233680 10746916864 1350043104 14.148 96 11 22 0 1363679120 11212656 2423360
00:39:06 55681 8192000000 10303581776 10753339392 1376597888 14.386 98 16 32 0 1391590880 12051984 2941008
00:41:12 61334 8192000000 10262941200 10713948160 1277055744 13.486 92 11 21 0 1289210496 10258240 1896512
00:43:36 69333 8192000000 10260969968 10694107136 1280094720 13.514 95 32 62 0 1299036208 12349040 6592448
00:45:49 57530 8192000000 10241834256 10696794112 1278642976 13.501 95 10 20 0 1294328752 13673168 2012608
00:47:47 94698 8192000000 10262858112 10693664768 1309968816 13.786 97 43 86 0 1329607072 9785904 9852352
00:50:24 51629 8192000000 10258730176 10713554944 1347751056 14.127 100 22 44 0 1366894032 14365280 4777696
00:52:30 67143 8192000000 10251904832 10704830464 1331244400 13.978 98 16 32 0 1344129632 9075280 3809952
00:54:49 92040 8192000000 10268789632 10722193408 1343934192 14.093 98 14 28 0 1360326640 13253088 3139360
00:57:28 56826 8192000000 10234399264 10702229504 1325040000 13.922 98 8 16 0 1345126912 17875008 2211904
00:59:57 41699 8192000000 10262791456 10703814656 1340552560 14.062 99 27 54 0 1360553200 14391904 5608736
01:01:40 46568 8192000000 10254133056 10710269952 1344426320 14.097 99 14 28 0 1360304512 12296000 3582192
01:03:28 98788 8192000000 10266684416 10718638080 1344796864 14.101 98 12 23 0 1355665456 8706720 2161872
01:06:07 18032 8192000000 10300908896 10747236352 1384224224 14.454 100 35 70 0 1407254304 15056432 7973648
01:07:26 74752 8192000000 10294276448 10763276288 1380436064 14.420 99 12 24 0 1402518240 19698560 2383616
01:09:42 81473 8192000000 10323455728 10797821952 1406523056 14.653 100 18 36 0 1429065440 18716816 3825568
01:12:06 108618 8192000000 10298670384 10757328896 1382192144 14.436 98 14 28 1 1399089520 13801648 3095728
01:14:57 70464 8192000000 10311100928 10777677824 1402019552 14.613 99 14 28 0 1414798672 9574208 3204912
01:17:09 64073 8192000000 10322494416 10783948800 1410515792 14.689 100 18 37 0 1428971920 13958576 4497552
01:19:18 131571 8192000000 10299541584 10766041088 1350359856 14.151 96 20 39 0 1375568576 20524208 4684512
01:22:32 138476 8192000000 10298085712 10741280768 1292325376 13.625 92 10 20 0 1311863264 17548384 1989504
01:25:53 85207 8192000000 10301947760 10752057344 1342911056 14.084 96 14 28 0 1357481344 12125760 2444528
01:28:20 58994 8192000000 10285086784 10738929664 1329787552 13.965 95 20 40 0 1341987872 7560512 4639808
01:30:23 159266 8192000000 10283666704 10744471552 1368079504 14.310 99 22 44 0 1388523936 15357088 5087344
01:34:04 47013 8192000000 10251165824 10711011328 1336651632 14.027 97 12 24 0 1347210880 8029760 2529488
01:35:53 67220 8192000000 10250360464 10698452992 1311143024 13.796 96 6 12 0 1318736816 6344896 1248896
01:38:02 40518 8192000000 9448127312 9765842944 579367232 6.605 43 4 8 0 584288096 4232400 688464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2022 01:40:30 to 22-12-2022 01:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2022 01:40:46 to 22-12-2022 01:40:48.

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          241740
MON$OLDEST_ACTIVE               242635
MON$OLDEST_SNAPSHOT             242635
MON$NEXT_TRANSACTION            242635
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                       2031584
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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'

22.12.22 01:40:49. 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-12-22 01:40:52 No severe PSQL-related problems occured
22.12.22 01:40:53. End of report.