Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2023 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 15001276 kB
MemAvailable 30387464 kB
Buffers 0 kB
Cached 16688856 kB
SwapCached 2484 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 2023-01-18 01:38:37
DTS_END                         2023-01-18 01:38:37.9220
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/39434
TRN_ID                          238629
ATT_ID                          645
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: 9728.45 21469 1167414
customer order: creation 168.63 361 20235
customer order: refuse 14.42 342 1730
order to supplier: creation 44.81 1203 5377
order to supplier: removal 3.64 106004 437
invoice (draft): creation 44.83 3208 5380
invoice (draft): removal 58.77 733 7052
invoice accept: apply 40.01 16944 4801
invoice accept: cancel 52.95 783 6354
customer reserve: creation 3761.98 164 451438
customer reserve: removal 5247.15 58 629658
realization accept: apply 80.72 169 9686
realization accept: cancel 83.73 111 10047
payment from customer: creation 58.32 116 6998
payment from customer: removal 5.68 121 682
payment to supplier: creation 47.61 136 5713
payment to supplier: removal 3.72 85 446
service: total inventory turnovers 4.43 1332 531
service: total monetary turnovers 6.25 49 750
service: aggregate perf. data 0.79 21588 95
service: refresh index statistics 0.03 275878 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-01-2023 01:39:25 to 18-01-2023 01:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2745
TEST_TIME 2 3371
TEST_TIME 3 3733
TEST_TIME 4 5081
TEST_TIME 5 5411
TEST_TIME 6 6152
TEST_TIME 7 6345
TEST_TIME 8 6314
TEST_TIME 9 5923
TEST_TIME 10 6129
TEST_TIME 11 5922
TEST_TIME 12 5888
TEST_TIME 13 6047
TEST_TIME 14 6180
TEST_TIME 15 6284
TEST_TIME 16 6447
TEST_TIME 17 6710
TEST_TIME 18 6782
TEST_TIME 19 6728
TEST_TIME 20 6745
TEST_TIME 21 7066
TEST_TIME 22 7033
TEST_TIME 23 7531
TEST_TIME 24 7564
TEST_TIME 25 7662
TEST_TIME 26 7735
TEST_TIME 27 7727
TEST_TIME 28 7965
TEST_TIME 29 8844
TEST_TIME 30 9044
TEST_TIME 31 9208
TEST_TIME 32 10203
TEST_TIME 33 10346
TEST_TIME 34 10269
TEST_TIME 35 10439
TEST_TIME 36 10416
TEST_TIME 37 10367
TEST_TIME 38 10198
TEST_TIME 39 10207
TEST_TIME 40 10346
TEST_TIME 41 10279
TEST_TIME 42 10216
TEST_TIME 43 10053
TEST_TIME 44 9952
TEST_TIME 45 9996
TEST_TIME 46 10006
TEST_TIME 47 9909
TEST_TIME 48 9900
TEST_TIME 49 9836
TEST_TIME 50 9844
TEST_TIME 51 9977
TEST_TIME 52 9929
TEST_TIME 53 9893
TEST_TIME 54 9891
TEST_TIME 55 9789
TEST_TIME 56 9768
TEST_TIME 57 9744
TEST_TIME 58 9701
TEST_TIME 59 9639
TEST_TIME 60 9597
TEST_TIME 61 9515
TEST_TIME 62 9494
TEST_TIME 63 9581
TEST_TIME 64 9545
TEST_TIME 65 9564
TEST_TIME 66 9595
TEST_TIME 67 9599
TEST_TIME 68 9709
TEST_TIME 69 9628
TEST_TIME 70 9751
TEST_TIME 71 9770
TEST_TIME 72 9846
TEST_TIME 73 9789
TEST_TIME 74 9783
TEST_TIME 75 9842
TEST_TIME 76 9935
TEST_TIME 77 9816
TEST_TIME 78 9806
TEST_TIME 79 9796
TEST_TIME 80 9757
TEST_TIME 81 9717
TEST_TIME 82 9658
TEST_TIME 83 9633
TEST_TIME 84 9609
TEST_TIME 85 9572
TEST_TIME 86 9518
TEST_TIME 87 9539
TEST_TIME 88 9573
TEST_TIME 89 9553
TEST_TIME 90 9582
TEST_TIME 91 9588
TEST_TIME 92 9729
TEST_TIME 93 9720
TEST_TIME 94 9778
TEST_TIME 95 9845
TEST_TIME 96 9851
TEST_TIME 97 9899
TEST_TIME 98 9892
TEST_TIME 99 9937
TEST_TIME 100 9853
TEST_TIME 101 9770
TEST_TIME 102 9798
TEST_TIME 103 9782
TEST_TIME 104 9758
TEST_TIME 105 9719
TEST_TIME 106 9686
TEST_TIME 107 9665
TEST_TIME 108 9637
TEST_TIME 109 9641
TEST_TIME 110 9647
TEST_TIME 111 9648
TEST_TIME 112 9624
TEST_TIME 113 9661
TEST_TIME 114 9643
TEST_TIME 115 9715
TEST_TIME 116 9718
TEST_TIME 117 9734
TEST_TIME 118 9736
TEST_TIME 119 9752
TEST_TIME 120 9730

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-01-2023 01:39:29 to 18-01-2023 01:39:33.

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 1586814 1586814 0 0.00 0 31996 5 0 0
doc_list_biud 1586813 1586813 0 0.00 0 34129 29 0 0
sp_add_invoice_to_stock 4941 4801 140 2.83 23 176916 16944 0 140
sp_cancel_adding_invoice 6354 6354 0 0.00 3 79449 784 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 15225 342 0 0
sp_cancel_customer_reserve 629658 629658 0 0.00 0 34130 58 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 13883 122 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 2 3986 85 0 0
sp_cancel_supplier_invoice 7062 7052 10 0.14 1 19285 734 0 10
sp_cancel_supplier_order 437 437 0 0.00 11 1111772 106004 0 0
sp_cancel_write_off 10047 10047 0 0.00 1 16922 112 0 0
sp_client_order 20235 20235 0 0.00 3 36654 361 0 0
sp_customer_reserve 451438 451438 0 0.00 0 88289 164 0 0
sp_fill_shopping_cart 30976 30976 0 0.00 1 23597 37 0 0
sp_fill_shopping_cart_clo_res 451435 451435 0 0.00 0 17957 14 0 0
sp_get_clo_for_invoice 4797 4797 0 0.00 0 25394 295 0 0
sp_kill_cost_storno 17529 17529 0 0.00 0 16920 54 0 0
sp_kill_qstorno_ret_qs2qd 1276852 1276852 0 0.00 0 34079 31 0 0
sp_kill_qty_storno 669761 669761 0 0.00 0 34128 67 0 0
sp_lock_selected_doc 20213 20213 0 0.00 0 7875 6 0 0
sp_make_cost_storno 27194 27194 0 0.00 0 15412 58 0 0
sp_multiply_rows_for_pdistr 27194 27194 0 0.00 0 9664 27 0 0
sp_multiply_rows_for_qdistr 20235 20235 0 0.00 0 26731 161 0 0
sp_pay_from_customer 6998 6998 0 0.00 4 16957 116 0 0
sp_pay_to_supplier 5713 5713 0 0.00 5 14318 137 0 0
sp_payment_common 12711 12711 0 0.00 1 16841 31 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 15220 318 0 0
sp_qd_handle_on_invoice_upd_sts 11151 11151 0 0.00 0 19793 335 0 0
sp_qd_handle_on_reserve_upd_sts 9686 9686 0 0.00 0 12679 56 0 0
sp_reserve_write_off 9686 9686 0 0.00 6 22690 169 0 0
sp_supplier_invoice 5380 5380 0 0.00 6 88804 3208 0 0
sp_supplier_order 5377 5377 0 0.00 5 49058 1203 0 0
srv_aggregate_perf_data 95 95 0 0.00 2946 198433 21588 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2405 135755 43077 0 0
srv_find_qd_qs_mism 1121376 1121376 0 0.00 0 31995 4 0 0
srv_make_invnt_saldo 531 531 0 0.00 55 110225 1333 0 0
srv_make_money_saldo 750 750 0 0.00 1 15371 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 144567 389119 275879 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 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 4801 4801 0 0.00 0 7570 176 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 13397 158 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 1532 10 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 3012 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 823 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 2274 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 819 14 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 21007 1550 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 1784 7 0 0
t$perf-norm:sp_client_order 20235 20235 0 0.00 0 5270 16 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 1010 5 0 0
t$perf-norm:sp_pay_from_customer 6998 6998 0 0.00 0 4100 8 0 0
t$perf-norm:sp_pay_to_supplier 5713 5713 0 0.00 0 5138 9 0 0
t$perf-norm:sp_reserve_write_off 9686 9686 0 0.00 0 4623 9 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 3688 13 0 0
t$perf-norm:sp_supplier_order 5377 5377 0 0.00 0 12805 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 616 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 2441 36 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 1021 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9872 9592 280 2.83 0 4563 18 0 280
v_all_customers 36740 36740 0 0.00 0 32 1 0 0
v_all_suppliers 29782 29782 0 0.00 0 20 1 0 0
v_all_wares 40470 40470 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 272 6 0 0
v_cancel_client_order 3460 3460 0 0.00 0 1490 4 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3566 7 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 90 3 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 3976 10 0 0
v_cancel_supplier_invoice 822 802 20 2.43 0 902 15 0 20
v_cancel_supplier_order 874 874 0 0.00 0 1627 4 0 0
v_cancel_write_off 1990 1990 0 0.00 0 3774 16 0 0
v_max_id_clo_ord 5374 5374 0 0.00 0 372 1 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 4462 17 0 0
v_max_id_ord_sup 5367 5367 0 0.00 0 4070 5 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 3647 8 0 0
v_max_non_paid_realizn 3719 3719 0 0.00 0 3077 4 0 0
v_min_id_clo_ord 5374 5374 0 0.00 0 4277 7 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 5669 10 0 0
v_min_id_ord_sup 5367 5367 0 0.00 0 1905 4 0 0
v_min_non_paid_invoice 5713 5713 0 0.00 0 6958 19 0 0
v_min_non_paid_realizn 6998 6998 0 0.00 0 16838 23 0 0
v_our_firm 10288 10288 0 0.00 0 43 1 0 0
v_reserve_write_off 19370 19370 0 0.00 0 4893 6 0 0
x_lock_depdocs_on_canc_invoice 13005 13005 0 0.00 0 16406 115 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 12782 427 0 0
x_make_qstorno_1000_1200 5377 5377 0 0.00 1 49023 1128 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 2 88789 3076 0 0
x_make_qstorno_2100_3300 451438 451438 0 0.00 0 88285 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2023 01:39:34 to 18-01-2023 01:39:38.

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:41 35 8192000000 8826242144 8847007744 23392144 0.284 7 5 6 0 23664384 100800 171440
23:09:42 11325 8192000000 9883168672 10190811136 974388448 10.630 100 85 169 0 1001936704 5590096 21958160
23:10:59 20679 8192000000 9943961200 10268209152 1030498576 11.173 100 84 168 0 1059415952 7407440 21509936
23:12:25 11331 8192000000 9979581408 10310320128 1068269088 11.536 100 87 174 0 1099683216 9150032 22264096
23:13:41 12661 8192000000 10020484320 10362114048 1103753168 11.873 100 82 164 0 1134028000 10537296 19737536
23:14:59 4753 8192000000 10084554384 10439929856 1167201472 12.471 100 86 172 0 1199061072 12192240 19667360
23:16:06 3444 8192000000 10124362848 10490916864 1209721712 12.867 100 79 154 0 1239397216 12661248 17014256
23:17:12 2528 8192000000 10150573968 10522206208 1237935504 13.127 100 93 184 0 1272390176 14271232 20183440
23:18:17 9409 8192000000 10171888864 10554236928 1266909424 13.393 100 69 136 0 1293898944 12286944 14702576
23:19:27 1053 8192000000 10201351696 10584879104 1290671504 13.610 100 91 177 1 1323384432 12713264 19999664
23:20:29 758 8192000000 10209757072 10595827712 1298222912 13.679 100 84 168 0 1331291232 14380272 18688048
23:21:30 5290 8192000000 10189623120 10604011520 1307316416 13.762 100 22 40 1 1317123200 5306720 4500064
23:22:39 5609 8192000000 10208425648 10623315968 1319492048 13.872 100 19 38 1 1330716816 7131664 4093104
23:23:51 11544 8192000000 10210328464 10632245248 1327163552 13.942 100 12 24 0 1339732112 10109472 2459088
23:25:03 65617 8192000000 10228146384 10648694784 1334850736 14.011 100 40 80 0 1351030384 5432192 10747456
23:27:12 4625 8192000000 10236213472 10655387648 1339270848 14.051 100 37 74 1 1354145248 6525104 8349296
23:28:19 7957 8192000000 10208968320 10632499200 1313788864 13.820 99 24 48 0 1326197264 6889776 5518624
23:29:27 17963 8192000000 10172932016 10581901312 1217152896 12.935 93 28 56 1 1228352192 4633344 6565952
23:30:45 4813 8192000000 10152928816 10560036864 1225231728 13.010 96 29 58 1 1238660288 7382192 6046368
23:31:53 4625 8192000000 10135411696 10531672064 1228985904 13.045 98 29 58 2 1242960272 6336784 7637584
23:32:59 5936 8192000000 10152083744 10544857088 1238968128 13.137 99 28 56 0 1251339744 6033520 6338096
23:34:07 13572 8192000000 10166998736 10574102528 1279680576 13.510 100 22 43 0 1290420336 5457728 5282032
23:35:23 23002 8192000000 10178072928 10588811264 1289873776 13.603 100 13 26 0 1298200704 5354672 2972256
23:36:47 5196 8192000000 10198580368 10610966528 1303296736 13.725 100 28 56 0 1320305664 10665376 6343552
23:37:52 8093 8192000000 10197933472 10616483840 1311820992 13.803 100 16 32 0 1320042592 5107120 3114480
23:39:05 11431 8192000000 10243545456 10652418048 1342010288 14.076 100 38 76 0 1357212480 6283536 8918656
23:40:20 4923 8192000000 10262010624 10671935488 1354296512 14.186 100 51 103 0 1375573728 10019616 11257600
23:41:30 76966 8192000000 10258832192 10692276224 1364687104 14.279 100 41 83 2 1387276896 12815360 9774432
23:43:53 9057 8192000000 10296733376 10715115520 1382133472 14.436 100 71 137 2 1409038448 11640928 15264048
23:45:04 3915 8192000000 10302102432 10722832384 1391731776 14.521 100 65 127 1 1417380880 11566656 14082448
23:46:13 50156 8192000000 10273663216 10702487552 1350374528 14.151 97 39 76 0 1369954176 10863200 8716448
23:48:07 11976 8192000000 10300871632 10727444480 1378566640 14.404 99 42 84 0 1405213616 16925648 9721328
23:49:31 11873 8192000000 10323251296 10755579904 1409932848 14.683 100 45 88 0 1439622224 18908368 10781008
23:50:52 22942 8192000000 10317670096 10754187264 1404903280 14.639 100 31 62 0 1435199296 22469520 7826496
23:52:26 5798 8192000000 10322948304 10748899328 1395924832 14.559 100 46 92 0 1432087392 24843152 11319408
23:53:39 6096 8192000000 10316013840 10732490752 1373262720 14.356 99 51 101 2 1414457456 28162848 13031888
23:54:51 3046 8192000000 10313322816 10726019072 1376057152 14.381 100 61 122 0 1421433824 29833392 15543280
23:56:00 2404 8192000000 10309028992 10717937664 1355102768 14.193 99 56 111 0 1403102608 34304496 13695344
23:57:08 2872 8192000000 10312709824 10708054016 1358149712 14.221 99 67 132 0 1412766240 37971584 16644944
23:58:19 4776 8192000000 10321293648 10727206912 1361045504 14.247 99 65 128 0 1420162960 43211328 15906128
23:59:33 3789 8192000000 10347255152 10752229376 1382006048 14.434 100 77 154 0 1452202832 51106736 19090048
00:00:40 2591 8192000000 10351191296 10754899968 1387587200 14.484 100 79 157 0 1456392752 49542144 19263408
00:01:48 15205 8192000000 10355194112 10768584704 1398469728 14.581 100 47 93 0 1452879472 41987728 12422016
00:03:12 7086 8192000000 10370039168 10787217408 1406264640 14.651 100 53 103 1 1472729552 53133184 13331728
00:04:25 5842 8192000000 10381362096 10801508352 1417750832 14.753 100 48 96 1 1485546768 54620800 13175136
00:05:35 8581 8192000000 10381405792 10805776384 1419738416 14.770 100 50 100 0 1491309264 57695136 13875712
00:06:49 6795 8192000000 10367775056 10790359040 1396522784 14.564 100 46 92 0 1475037328 65700784 12813760
00:08:17 28338 8192000000 10333999600 10778308608 1388236592 14.490 100 26 52 0 1447892944 51854048 7802304
00:10:28 43654 8192000000 10285613408 10737528832 1347600000 14.126 98 7 14 0 1380642800 31457984 1584816
00:12:21 135755 8192000000 10305280992 10757771264 1360335344 14.240 98 9 18 0 1383278224 20742976 2199904
00:16:28 85214 8192000000 10319921232 10765361152 1387574384 14.484 99 19 39 0 1415594848 23198432 4822032
00:19:07 104056 8192000000 10318473056 10774310912 1405077760 14.640 100 27 54 0 1422565808 11126048 6362000
00:21:51 68859 8192000000 10325052784 10776027136 1412387344 14.705 100 26 52 0 1432582864 13505968 6689552
00:24:33 79889 8192000000 10323493392 10783776768 1417480144 14.750 100 24 48 0 1434372256 11589008 5303104
00:26:54 10052 8192000000 10321455616 10780381184 1412106096 14.703 100 29 57 0 1435475152 17144480 6224576
00:28:14 35793 8192000000 10310624640 10774671360 1397820512 14.576 99 40 76 1 1420542400 13149840 9572048
00:30:00 39976 8192000000 10308553072 10780835840 1396746464 14.566 100 9 18 1 1423310720 24249200 2315056
00:31:49 24514 8192000000 10307256512 10778845184 1400462848 14.599 100 5 10 0 1421263376 19697008 1103520
00:33:14 47122 8192000000 10313492864 10787090432 1406710432 14.655 100 11 21 0 1429170976 19780192 2680352
00:35:12 32281 8192000000 10301326768 10749878272 1379958896 14.416 99 26 53 0 1396107120 9511024 6637200
00:36:45 45541 8192000000 10302381360 10767966208 1390645312 14.512 99 14 28 0 1406536128 12857376 3033440
00:38:43 60862 8192000000 10306957712 10784702464 1402827312 14.620 100 13 26 0 1424053744 18590704 2635728
00:40:44 72527 8192000000 10281061712 10756190208 1367853584 14.308 98 10 21 1 1387721648 17707088 2160976
00:43:05 21806 8192000000 10286734576 10740613120 1381384208 14.429 100 45 91 0 1400000048 8627888 9987952
00:44:38 38323 8192000000 10280728144 10739650560 1363029680 14.265 99 16 33 0 1379836592 13063120 3743792
00:46:17 71303 8192000000 10260903744 10713030656 1300108480 13.696 95 16 31 0 1319716000 15766608 3840912
00:48:29 59898 8192000000 10276690144 10735517696 1335015072 14.012 98 16 32 0 1352873472 14398592 3459808
00:50:30 76818 8192000000 10262328512 10711531520 1310803408 13.793 96 10 20 0 1325518848 12391696 2323744
00:52:58 64733 8192000000 10262913280 10707079168 1288536288 13.591 95 22 44 0 1305685360 12281136 4867936
00:55:27 60263 8192000000 10270472912 10717319168 1334183872 14.005 98 31 62 0 1363663344 22562640 6916832
00:57:36 18048 8192000000 10262908272 10734125056 1361816176 14.254 100 2 4 0 1377767312 15854848 96288
00:58:55 61418 8192000000 10265828688 10728849408 1374223536 14.365 100 16 32 0 1386709120 8744448 3741136
01:00:56 57624 8192000000 10271975776 10732040192 1350256912 14.150 98 10 20 0 1362160304 9441776 2461616
01:03:05 85878 8192000000 10260889824 10720575488 1345146096 14.104 98 14 28 0 1361942208 13639376 3156736
01:06:04 48142 8192000000 10272079072 10729680896 1358051776 14.220 99 12 24 0 1371445472 10641680 2752016
01:08:00 66735 8192000000 10300039520 10753798144 1379744576 14.414 99 21 42 0 1399428928 14515392 5168960
01:10:07 25679 8192000000 10292850128 10755297280 1393020720 14.533 100 32 63 1 1410076272 10124112 6931440
01:11:34 94391 8192000000 10290147312 10751700992 1354272464 14.186 97 10 20 0 1367382288 11096800 2013024
01:14:14 97744 8192000000 10316615760 10781691904 1374513088 14.367 98 11 20 0 1395721504 19240352 1968064
01:16:56 29769 8192000000 10324094304 10768138240 1403604320 14.627 100 41 82 0 1424357264 12022560 8730384
01:18:30 133159 8192000000 10306056992 10779385856 1391562944 14.520 99 15 30 0 1413659792 18451456 3645392
01:21:44 52171 8192000000 10289697920 10764279808 1361014640 14.246 97 7 14 0 1370677696 8355024 1308032
01:23:36 72006 8192000000 10295342048 10764341248 1400352672 14.598 100 13 26 0 1413229360 10381744 2494944
01:25:48 79625 8192000000 10300989648 10768728064 1389010320 14.497 99 20 40 0 1406698688 13149232 4539136
01:29:19 63820 8192000000 10286838240 10751016960 1356282240 14.204 97 9 18 0 1371694704 13487776 1924688
01:31:27 41943 8192000000 10287365152 10742382592 1342978704 14.084 96 16 32 0 1355241968 8600352 3662912
01:33:11 48809 8192000000 10274696576 10733023232 1353280400 14.177 98 14 28 0 1370083056 13649728 3152928
01:35:04 42759 8192000000 10270450928 10728525824 1339130016 14.050 97 18 36 0 1353663088 10325504 4207568
01:36:57 51454 8192000000 10260040128 10712354816 1304572960 13.737 95 16 32 0 1320158112 12436912 3148240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-01-2023 01:39:40 to 18-01-2023 01:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 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 18-01-2023 01:39:56 to 18-01-2023 01:39:58.

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          237395
MON$OLDEST_ACTIVE               239350
MON$OLDEST_SNAPSHOT             239350
MON$NEXT_TRANSACTION            239350
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                       2065120
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Jan 18 01:38:40 2023
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Wed Jan 18 01:38:41 2023
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Wed Jan 18 01:38:51 2023
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
--- end of diff output ---

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

18.01.23 01:39:59. 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
2023-01-18 01:40:01 No severe PSQL-related problems occured
18.01.23 01:40:03. End of report.