Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.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 15582424 kB
MemAvailable 30420088 kB
Buffers 0 kB
Cached 16119068 kB
SwapCached 4344 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-30 01:38:38
DTS_END                         2023-01-30 01:38:38.4160
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/53698
TRN_ID                          243080
ATT_ID                          669
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: 9055.97 21560 1086716
customer order: creation 175.91 377 21109
customer order: refuse 14.92 317 1790
order to supplier: creation 46.83 1118 5619
order to supplier: removal 3.23 96124 387
invoice (draft): creation 47.76 2995 5731
invoice (draft): removal 49.06 720 5887
invoice accept: apply 42.98 16911 5158
invoice accept: cancel 43.93 757 5271
customer reserve: creation 4010.03 166 481203
customer reserve: removal 4323.23 58 518788
realization accept: apply 86.80 166 10416
realization accept: cancel 78.56 100 9427
payment from customer: creation 60.03 116 7204
payment from customer: removal 5.86 80 703
payment to supplier: creation 50.42 119 6050
payment to supplier: removal 4.02 76 482
service: total inventory turnovers 5.11 926 613
service: total monetary turnovers 6.48 29 777
service: aggregate perf. data 0.81 20870 97
service: refresh index statistics 0.03 289179 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2023 01:39:55 to 30-01-2023 01:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2693
TEST_TIME 2 3331
TEST_TIME 3 4056
TEST_TIME 4 4326
TEST_TIME 5 5256
TEST_TIME 6 5300
TEST_TIME 7 5140
TEST_TIME 8 5499
TEST_TIME 9 5535
TEST_TIME 10 5495
TEST_TIME 11 5554
TEST_TIME 12 5496
TEST_TIME 13 5955
TEST_TIME 14 6256
TEST_TIME 15 6352
TEST_TIME 16 6675
TEST_TIME 17 7693
TEST_TIME 18 7516
TEST_TIME 19 7696
TEST_TIME 20 7975
TEST_TIME 21 8546
TEST_TIME 22 8736
TEST_TIME 23 9220
TEST_TIME 24 9562
TEST_TIME 25 9844
TEST_TIME 26 10537
TEST_TIME 27 10637
TEST_TIME 28 10582
TEST_TIME 29 10342
TEST_TIME 30 10180
TEST_TIME 31 10189
TEST_TIME 32 9918
TEST_TIME 33 9904
TEST_TIME 34 9892
TEST_TIME 35 9721
TEST_TIME 36 9781
TEST_TIME 37 9796
TEST_TIME 38 9625
TEST_TIME 39 9630
TEST_TIME 40 9526
TEST_TIME 41 9483
TEST_TIME 42 9500
TEST_TIME 43 9547
TEST_TIME 44 9475
TEST_TIME 45 9317
TEST_TIME 46 9292
TEST_TIME 47 9260
TEST_TIME 48 9147
TEST_TIME 49 9044
TEST_TIME 50 9061
TEST_TIME 51 9083
TEST_TIME 52 9008
TEST_TIME 53 9037
TEST_TIME 54 9106
TEST_TIME 55 9031
TEST_TIME 56 9140
TEST_TIME 57 9161
TEST_TIME 58 9187
TEST_TIME 59 9215
TEST_TIME 60 9214
TEST_TIME 61 9176
TEST_TIME 62 9291
TEST_TIME 63 9234
TEST_TIME 64 9273
TEST_TIME 65 9387
TEST_TIME 66 9312
TEST_TIME 67 9200
TEST_TIME 68 9193
TEST_TIME 69 9193
TEST_TIME 70 9176
TEST_TIME 71 9148
TEST_TIME 72 9107
TEST_TIME 73 9109
TEST_TIME 74 9054
TEST_TIME 75 9034
TEST_TIME 76 9019
TEST_TIME 77 9003
TEST_TIME 78 8986
TEST_TIME 79 9012
TEST_TIME 80 8965
TEST_TIME 81 9152
TEST_TIME 82 9172
TEST_TIME 83 9147
TEST_TIME 84 9193
TEST_TIME 85 9186
TEST_TIME 86 9266
TEST_TIME 87 9277
TEST_TIME 88 9299
TEST_TIME 89 9261
TEST_TIME 90 9194
TEST_TIME 91 9171
TEST_TIME 92 9150
TEST_TIME 93 9101
TEST_TIME 94 9093
TEST_TIME 95 9075
TEST_TIME 96 9061
TEST_TIME 97 9090
TEST_TIME 98 9155
TEST_TIME 99 9137
TEST_TIME 100 9144
TEST_TIME 101 9124
TEST_TIME 102 9205
TEST_TIME 103 9175
TEST_TIME 104 9209
TEST_TIME 105 9225
TEST_TIME 106 9237
TEST_TIME 107 9238
TEST_TIME 108 9207
TEST_TIME 109 9300
TEST_TIME 110 9235
TEST_TIME 111 9204
TEST_TIME 112 9162
TEST_TIME 113 9196
TEST_TIME 114 9161
TEST_TIME 115 9122
TEST_TIME 116 9116
TEST_TIME 117 9096
TEST_TIME 118 9119
TEST_TIME 119 9101
TEST_TIME 120 9060

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2023 01:39:58 to 30-01-2023 01:40:02.

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 1529959 1529959 0 0.00 0 16102 6 0 0
doc_list_biud 1529954 1529954 0 0.00 0 50974 25 0 0
sp_add_invoice_to_stock 5297 5158 139 2.62 17 171194 16912 0 139
sp_cancel_adding_invoice 5271 5271 0 0.00 2 52466 757 0 0
sp_cancel_client_order 1790 1790 0 0.00 5 13523 318 0 0
sp_cancel_customer_reserve 518788 518788 0 0.00 0 50979 58 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 5255 80 0 0
sp_cancel_pay_to_supplier 482 482 0 0.00 3 5406 76 0 0
sp_cancel_supplier_invoice 5896 5887 9 0.15 1 13556 720 0 9
sp_cancel_supplier_order 387 387 0 0.00 10 973472 96124 0 0
sp_cancel_write_off 9427 9427 0 0.00 1 9641 101 0 0
sp_client_order 21109 21109 0 0.00 3 37836 378 0 0
sp_customer_reserve 481203 481203 0 0.00 0 48053 167 0 0
sp_fill_shopping_cart 32454 32454 0 0.00 1 15427 37 0 0
sp_fill_shopping_cart_clo_res 481196 481196 0 0.00 0 9717 13 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 35827 319 0 0
sp_kill_cost_storno 15883 15883 0 0.00 0 9247 52 0 0
sp_kill_qstorno_ret_qs2qd 1053277 1053277 0 0.00 0 50970 31 0 0
sp_kill_qty_storno 557111 557111 0 0.00 0 50974 67 0 0
sp_lock_selected_doc 21551 21551 0 0.00 0 7698 7 0 0
sp_make_cost_storno 28817 28817 0 0.00 0 12932 49 0 0
sp_multiply_rows_for_pdistr 28815 28815 0 0.00 0 9850 27 0 0
sp_multiply_rows_for_qdistr 21108 21108 0 0.00 0 37829 181 0 0
sp_pay_from_customer 7204 7204 0 0.00 3 17844 117 0 0
sp_pay_to_supplier 6050 6050 0 0.00 4 18188 120 0 0
sp_payment_common 13254 13254 0 0.00 1 11687 28 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 13484 284 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 11505 277 0 0
sp_qd_handle_on_reserve_upd_sts 10415 10415 0 0.00 0 16900 63 0 0
sp_reserve_write_off 10416 10416 0 0.00 6 17241 166 0 0
sp_supplier_invoice 5731 5731 0 0.00 6 73850 2995 0 0
sp_supplier_order 5619 5619 0 0.00 5 29347 1118 0 0
srv_aggregate_perf_data 97 97 0 0.00 2674 318370 20870 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1334 128306 47065 0 0
srv_find_qd_qs_mism 1036318 1036318 0 0.00 0 16102 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 36 67625 926 0 0
srv_make_money_saldo 777 777 0 0.00 1 6537 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 118135 536341 289179 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 9994 154 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 6610 96 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 1697 11 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 2564 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1293 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 482 482 0 0.00 0 1078 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 514 7 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 24669 1462 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 6090 11 0 0
t$perf-norm:sp_client_order 21109 21109 0 0.00 0 9618 15 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 3323 7 0 0
t$perf-norm:sp_pay_from_customer 7204 7204 0 0.00 0 6079 12 0 0
t$perf-norm:sp_pay_to_supplier 6050 6050 0 0.00 0 5923 10 0 0
t$perf-norm:sp_reserve_write_off 10416 10416 0 0.00 0 10537 12 0 0
t$perf-norm:sp_supplier_invoice 5731 5731 0 0.00 0 6554 19 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 5463 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1279 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 290 6 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 1678 26 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 1742 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10568 10290 278 2.63 0 4362 17 0 278
v_all_customers 38202 38202 0 0.00 0 171 0 0 0
v_all_suppliers 31450 31450 0 0.00 0 147 1 0 0
v_all_wares 42216 42216 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 2482 11 0 0
v_cancel_client_order 3580 3580 0 0.00 0 4671 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3856 6 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 1498 4 0 0
v_cancel_payment_to_supplier 964 964 0 0.00 0 901 6 0 0
v_cancel_supplier_invoice 858 840 18 2.09 0 1482 18 0 18
v_cancel_supplier_order 774 774 0 0.00 0 2378 6 0 0
v_cancel_write_off 2048 2048 0 0.00 0 4727 15 0 0
v_max_id_clo_ord 5619 5619 0 0.00 0 343 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 3679 25 0 0
v_max_id_ord_sup 5727 5727 0 0.00 0 5745 5 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 11681 9 0 0
v_max_non_paid_realizn 3858 3858 0 0.00 0 4426 6 0 0
v_min_id_clo_ord 5619 5619 0 0.00 0 1430 4 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 4435 14 0 0
v_min_id_ord_sup 5727 5727 0 0.00 0 6881 8 0 0
v_min_non_paid_invoice 6050 6050 0 0.00 0 5999 14 0 0
v_min_non_paid_realizn 7204 7204 0 0.00 0 9974 22 0 0
v_our_firm 10706 10706 0 0.00 0 145 1 0 0
v_reserve_write_off 20828 20828 0 0.00 0 6442 6 0 0
x_lock_depdocs_on_canc_invoice 10738 10738 0 0.00 0 8616 104 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 3701 310 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 1 29339 1053 0 0
x_make_qstorno_1200_2000 5731 5731 0 0.00 1 73825 2855 0 0
x_make_qstorno_2100_3300 481202 481202 0 0.00 0 48053 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 01:40:03 to 30-01-2023 01:40:07.

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:42 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:43 33637 8192000000 9902626592 10214514688 981602416 10.700 100 84 168 0 1009963136 6335904 22024816
23:11:20 18482 8192000000 9941404416 10262044672 1023568480 11.106 100 88 177 0 1053958064 8155312 22234272
23:12:43 14729 8192000000 9985238032 10318262272 1067765344 11.531 100 83 166 0 1097736608 9563520 20407744
23:14:02 11719 8192000000 10020743424 10367586304 1112288336 11.954 100 73 145 0 1139207408 10022352 16896720
23:15:19 4808 8192000000 10082559536 10438959104 1168043216 12.479 100 83 165 0 1199100128 12420352 18636560
23:16:27 4201 8192000000 10122196128 10490949632 1210301808 12.872 100 78 156 0 1240712464 12638624 17772032
23:17:33 1821 8192000000 10159663664 10530648064 1243925120 13.182 100 89 178 0 1277751248 14193888 19632240
23:18:36 1481 8192000000 10189635280 10569031680 1276316048 13.479 100 86 173 1 1309717872 14115392 19286432
23:19:39 879 8192000000 10217779344 10603806720 1303220176 13.725 100 84 166 0 1336713232 13925344 19567712
23:20:40 5741 8192000000 10208435024 10612985856 1311936400 13.804 100 42 81 0 1330155824 9878080 8341344
23:21:49 8757 8192000000 10202185680 10619183104 1319524912 13.872 100 14 28 1 1328585184 6104336 2955936
23:22:58 58463 8192000000 10215557520 10627956736 1322989616 13.904 100 31 61 0 1335434736 5240944 7204176
23:25:10 7068 8192000000 10224258272 10640920576 1332043920 13.986 100 24 48 0 1343913392 6107760 5761712
23:26:18 8244 8192000000 10229204256 10649542656 1341330112 14.069 100 15 30 0 1350921952 6147616 3444224
23:27:26 49407 8192000000 10234411344 10653843456 1330053152 13.968 99 30 59 4 1342275904 4432448 7790304
23:29:28 6893 8192000000 10184844320 10595270656 1278930272 13.503 98 17 34 0 1288173680 5328608 3914800
23:30:35 8304 8192000000 10170977136 10581082112 1280494496 13.518 100 28 56 1 1292791984 6055552 6241936
23:31:46 12171 8192000000 10123279584 10528464896 1205641984 12.829 97 19 38 0 1215459184 5478928 4338272
23:32:59 37259 8192000000 10134087328 10539487232 1230533264 13.059 98 30 58 1 1243887072 6704688 6649120
23:34:37 6044 8192000000 10172313904 10577952768 1276024976 13.477 100 19 36 1 1284677056 4639536 4012544
23:35:45 10751 8192000000 10178779856 10590879744 1289455248 13.599 100 17 34 1 1299081024 5335840 4289936
23:36:56 17009 8192000000 10197896224 10608529408 1304808096 13.739 100 28 55 2 1317036032 5846896 6381040
23:38:13 27078 8192000000 10223558560 10638483456 1322716624 13.901 100 25 50 0 1333525936 5963712 4845600
23:39:52 17066 8192000000 10260270672 10671853568 1342814160 14.083 100 60 120 1 1372136448 16133040 13189248
23:41:17 21224 8192000000 10271798800 10690060288 1360580480 14.243 100 46 92 0 1386781344 15633840 10567024
23:42:42 22663 8192000000 10283008880 10707640320 1370996144 14.336 100 38 76 0 1397868464 18018480 8853840
23:44:11 38188 8192000000 10300785616 10728230912 1373121936 14.355 99 43 85 1 1402398368 18598272 10678160
23:45:55 5865 8192000000 10317626400 10743267328 1391219840 14.517 100 43 83 0 1426015440 24526848 10268752
23:47:05 10545 8192000000 10328965584 10757591040 1409961456 14.684 100 52 103 0 1443698512 21670800 12066256
23:48:23 8670 8192000000 10340652912 10767716352 1413076352 14.711 100 54 107 1 1447963552 22607472 12279728
23:49:38 9406 8192000000 10353083936 10784014336 1420272384 14.775 100 52 100 1 1460684656 28116448 12295824
23:50:54 4723 8192000000 10374700320 10797785088 1433006336 14.888 100 68 131 0 1482557504 33317056 16234112
23:52:06 1333 8192000000 10366539328 10786136064 1422568432 14.795 100 71 142 0 1478614144 36985264 19060448
23:53:13 3741 8192000000 10364542048 10779275264 1413456544 14.715 100 78 152 1 1473046864 39882656 19707664
23:54:25 1648 8192000000 10370571552 10779324416 1405093696 14.640 100 79 156 0 1470895920 45877728 19924496
23:55:34 5801 8192000000 10314515552 10730491904 1346193296 14.113 97 52 104 0 1397832640 38592656 13046688
23:56:52 5849 8192000000 10338628400 10748940288 1384006240 14.452 100 60 117 2 1439564592 40051856 15506496
23:58:08 20289 8192000000 10341922432 10774577152 1380320016 14.419 99 42 85 1 1442023520 50139680 11563824
23:59:32 8545 8192000000 10327461168 10749964288 1366989456 14.300 99 41 80 1 1422940704 45306096 10645152
00:00:46 11124 8192000000 10335234864 10773143552 1375374112 14.375 99 36 71 0 1435849104 50924976 9550016
00:02:06 36085 8192000000 10310378384 10758361088 1381436080 14.429 100 24 48 0 1430174272 42565328 6172864
00:03:44 17562 8192000000 10308627152 10746064896 1347531616 14.125 98 23 45 0 1397424800 43823648 6069536
00:05:04 32972 8192000000 10268394448 10716479488 1343895008 14.093 98 25 47 0 1366352544 16308800 6148736
00:07:10 69184 8192000000 10264026608 10696359936 1331449360 13.980 98 12 24 0 1344524464 10509072 2566032
00:09:23 90082 8192000000 10260474064 10710675456 1337886528 14.038 98 16 32 0 1360428944 19375776 3166640
00:12:01 62625 8192000000 10254201600 10701156352 1300838624 13.703 95 21 42 1 1313176608 7508816 4829168
00:14:18 77137 8192000000 10260890144 10711199744 1358428656 14.223 100 12 24 0 1375172912 14386432 2357824
00:16:37 26747 8192000000 10271713776 10713944064 1347194784 14.122 99 23 46 0 1367815952 15787632 4833536
00:18:06 34734 8192000000 10299776576 10735353856 1374474560 14.367 100 38 76 0 1403885952 20866880 8544512
00:19:42 47696 8192000000 10284138176 10737192960 1378603072 14.404 100 17 34 1 1401998640 19229888 4165680
00:22:04 77343 8192000000 10284845344 10744631296 1383301504 14.446 100 10 20 0 1403412480 17511856 2599120
00:24:39 8356 8192000000 10290001008 10735738880 1385230000 14.463 100 39 76 2 1401314592 6970320 9114272
00:25:48 41410 8192000000 10282823824 10739408896 1389181008 14.499 100 10 20 0 1398747536 7381440 2185088
00:27:35 79901 8192000000 10280371200 10752663552 1382287168 14.437 99 11 22 0 1394026992 9273872 2465952
00:29:55 39890 8192000000 10283781856 10730835968 1354566896 14.189 97 26 52 0 1368010512 7330560 6113056
00:31:40 63188 8192000000 10288205456 10747834368 1368281968 14.312 98 15 30 1 1383830160 12118960 3429232
00:33:43 38455 8192000000 10302456256 10760462336 1402052160 14.613 100 24 48 0 1418798176 11424352 5321664
00:35:34 69280 8192000000 10299642000 10753126400 1389688208 14.503 99 21 42 1 1403112720 9031824 4392688
00:37:43 77213 8192000000 10318990544 10768568320 1394952560 14.550 99 24 48 1 1409308112 9251584 5103968
00:40:03 74088 8192000000 10281662560 10735513600 1285839344 13.566 92 37 74 0 1305157712 11583872 7734496
00:42:17 33106 8192000000 10275626240 10720370688 1314231152 13.824 95 20 40 0 1331102992 12321664 4550176
00:43:52 117933 8192000000 10262564368 10705428480 1312725376 13.811 96 23 46 1 1328889632 9705248 6459008
00:46:50 44938 8192000000 10278636608 10731638784 1367164032 14.302 100 32 62 0 1387562256 13464368 6933856
00:48:37 20014 8192000000 10254086448 10711097344 1328350352 13.952 97 15 30 0 1340948960 9397424 3201184
00:50:02 47152 8192000000 10267620880 10724196352 1364809296 14.281 100 19 38 1 1380848144 11514848 4524000
00:51:50 59892 8192000000 10249988704 10714517504 1357722224 14.217 100 8 16 0 1369170832 9806848 1641760
00:53:53 59181 8192000000 10256384176 10724712448 1355482304 14.197 99 13 26 0 1369037280 10712304 2842672
00:55:53 46253 8192000000 10282492176 10732519424 1347843712 14.128 98 20 40 0 1364315312 10907872 5563728
00:57:50 37808 8192000000 10281527584 10726998016 1346480928 14.116 98 18 36 0 1369801568 19006352 4314288
00:59:41 94826 8192000000 10241695344 10677723136 1241466272 13.160 92 24 49 0 1262473728 16073648 4933808
01:02:20 51038 8192000000 10253889360 10691309568 1327312288 13.943 99 27 52 0 1346961024 13838208 5810528
01:04:14 77538 8192000000 10266222176 10700820480 1336374992 14.025 99 26 52 0 1357060368 14725664 5959712
01:06:31 56544 8192000000 10261651152 10704744448 1338250240 14.042 99 23 46 0 1353416080 10366144 4799696
01:08:32 111495 8192000000 10270787696 10716876800 1328415936 13.953 97 32 62 0 1342295920 7413824 6466160
01:11:36 57985 8192000000 10257694016 10713542656 1366084848 14.292 100 9 18 0 1374723616 6622784 2015984
01:13:36 67849 8192000000 10300990000 10751913984 1389680512 14.503 100 17 35 0 1400718064 7472528 3565024
01:15:49 58783 8192000000 10284152832 10752688128 1368343904 14.312 98 15 28 1 1387072848 15337552 3391392
01:17:49 33129 8192000000 10300004208 10747121664 1385496656 14.466 100 39 76 0 1402285040 8278592 8509792
01:19:56 74198 8192000000 10292418224 10750242816 1372782448 14.352 99 15 30 0 1387560128 10717632 4060048
01:22:10 128306 8192000000 10293170656 10749526016 1375229744 14.374 99 16 32 0 1393451456 14912352 3309360
01:25:23 71275 8192000000 10278544576 10717114368 1278180352 13.496 92 11 22 0 1291289760 11074416 2034992
01:27:40 89583 8192000000 10275122640 10710384640 1339828976 14.056 98 22 44 0 1354653952 9597888 5227088
01:30:11 43380 8192000000 10275470080 10722213888 1360906624 14.245 100 28 56 2 1384388560 18031680 5450256
01:31:55 67361 8192000000 10233376336 10688024576 1310025904 13.786 97 12 24 0 1327461504 14567536 2868064
01:34:46 115069 8192000000 10254115504 10713137152 1320424032 13.881 98 14 28 0 1334633504 11077424 3132048
01:37:47 59700 8192000000 9495460592 9820479488 627149840 7.111 46 8 16 0 633796960 4901904 1745216

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-01-2023 01:40:09 to 30-01-2023 01:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 01:40:25 to 30-01-2023 01:40:27.

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          243887
MON$OLDEST_ACTIVE               243888
MON$OLDEST_SNAPSHOT             243888
MON$NEXT_TRANSACTION            243888
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                       2000640
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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.01.23 01:40:28. 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-30 01:40:30 No severe PSQL-related problems occured
30.01.23 01:40:32. End of report.