Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.02.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15187204 kB
MemAvailable 30427700 kB
Buffers 0 kB
Cached 16503944 kB
SwapCached 3080 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-02-28 22:38:40
DTS_END                         2023-02-28 22:38:40.7200
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/33342
TRN_ID                          243800
ATT_ID                          696
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: 9269.82 24574 1112378
customer order: creation 174.23 370 20907
customer order: refuse 15.03 354 1803
order to supplier: creation 46.68 1261 5602
order to supplier: removal 3.45 111152 414
invoice (draft): creation 46.01 3425 5521
invoice (draft): removal 53.14 951 6377
invoice accept: apply 41.74 17843 5009
invoice accept: cancel 47.95 835 5754
customer reserve: creation 3840.53 178 460864
customer reserve: removal 4694.93 64 563392
realization accept: apply 85.43 163 10252
realization accept: cancel 88.40 104 10608
payment from customer: creation 61.38 94 7366
payment from customer: removal 5.86 82 703
payment to supplier: creation 49.24 110 5909
payment to supplier: removal 3.38 87 405
service: total inventory turnovers 4.91 1116 589
service: total monetary turnovers 6.68 48 802
service: aggregate perf. data 0.81 23574 97
service: refresh index statistics 0.03 329677 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2023 22:39:28 to 28-02-2023 22:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2260
TEST_TIME 2 2884
TEST_TIME 3 4030
TEST_TIME 4 4599
TEST_TIME 5 5016
TEST_TIME 6 5004
TEST_TIME 7 5148
TEST_TIME 8 4770
TEST_TIME 9 5063
TEST_TIME 10 5575
TEST_TIME 11 5710
TEST_TIME 12 6268
TEST_TIME 13 6586
TEST_TIME 14 6524
TEST_TIME 15 6603
TEST_TIME 16 6673
TEST_TIME 17 6849
TEST_TIME 18 7165
TEST_TIME 19 7882
TEST_TIME 20 8029
TEST_TIME 21 8754
TEST_TIME 22 8729
TEST_TIME 23 8551
TEST_TIME 24 8523
TEST_TIME 25 8825
TEST_TIME 26 9030
TEST_TIME 27 9284
TEST_TIME 28 9512
TEST_TIME 29 10417
TEST_TIME 30 11025
TEST_TIME 31 10707
TEST_TIME 32 10529
TEST_TIME 33 10420
TEST_TIME 34 10316
TEST_TIME 35 10170
TEST_TIME 36 10101
TEST_TIME 37 9976
TEST_TIME 38 9852
TEST_TIME 39 9707
TEST_TIME 40 9765
TEST_TIME 41 9696
TEST_TIME 42 9638
TEST_TIME 43 9570
TEST_TIME 44 9509
TEST_TIME 45 9625
TEST_TIME 46 9563
TEST_TIME 47 9472
TEST_TIME 48 9345
TEST_TIME 49 9292
TEST_TIME 50 9296
TEST_TIME 51 9253
TEST_TIME 52 9245
TEST_TIME 53 9170
TEST_TIME 54 9119
TEST_TIME 55 9010
TEST_TIME 56 8966
TEST_TIME 57 9001
TEST_TIME 58 8969
TEST_TIME 59 8905
TEST_TIME 60 8891
TEST_TIME 61 8938
TEST_TIME 62 8985
TEST_TIME 63 9035
TEST_TIME 64 9024
TEST_TIME 65 9092
TEST_TIME 66 9236
TEST_TIME 67 9266
TEST_TIME 68 9271
TEST_TIME 69 9233
TEST_TIME 70 9354
TEST_TIME 71 9426
TEST_TIME 72 9411
TEST_TIME 73 9382
TEST_TIME 74 9301
TEST_TIME 75 9271
TEST_TIME 76 9226
TEST_TIME 77 9249
TEST_TIME 78 9218
TEST_TIME 79 9162
TEST_TIME 80 9077
TEST_TIME 81 9102
TEST_TIME 82 9098
TEST_TIME 83 9139
TEST_TIME 84 9113
TEST_TIME 85 9134
TEST_TIME 86 9084
TEST_TIME 87 9163
TEST_TIME 88 9287
TEST_TIME 89 9257
TEST_TIME 90 9260
TEST_TIME 91 9284
TEST_TIME 92 9314
TEST_TIME 93 9332
TEST_TIME 94 9379
TEST_TIME 95 9429
TEST_TIME 96 9405
TEST_TIME 97 9398
TEST_TIME 98 9340
TEST_TIME 99 9341
TEST_TIME 100 9292
TEST_TIME 101 9253
TEST_TIME 102 9256
TEST_TIME 103 9216
TEST_TIME 104 9199
TEST_TIME 105 9160
TEST_TIME 106 9116
TEST_TIME 107 9101
TEST_TIME 108 9114
TEST_TIME 109 9178
TEST_TIME 110 9193
TEST_TIME 111 9206
TEST_TIME 112 9198
TEST_TIME 113 9190
TEST_TIME 114 9187
TEST_TIME 115 9273
TEST_TIME 116 9273
TEST_TIME 117 9290
TEST_TIME 118 9289
TEST_TIME 119 9301
TEST_TIME 120 9271

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 22:39:31 to 28-02-2023 22:39:35.

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 1540352 1540352 0 0.00 0 25130 6 0 0
doc_list_biud 1540352 1540352 0 0.00 0 24114 30 0 0
sp_add_invoice_to_stock 5112 5009 103 2.01 36 210843 17844 0 103
sp_cancel_adding_invoice 5754 5754 0 0.00 4 92962 835 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 15970 354 0 0
sp_cancel_customer_reserve 563392 563392 0 0.00 0 23777 64 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 6984 82 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 4817 88 0 0
sp_cancel_supplier_invoice 6383 6377 6 0.09 1 24126 952 0 6
sp_cancel_supplier_order 414 414 0 0.00 10 1151737 111152 0 0
sp_cancel_write_off 10608 10608 0 0.00 1 9669 104 0 0
sp_client_order 20907 20907 0 0.00 3 23044 370 0 0
sp_customer_reserve 460864 460864 0 0.00 0 40706 178 0 0
sp_fill_shopping_cart 32019 32019 0 0.00 1 16044 40 0 0
sp_fill_shopping_cart_clo_res 460860 460860 0 0.00 0 13605 14 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 32602 338 0 0
sp_kill_cost_storno 17470 17470 0 0.00 0 25122 57 0 0
sp_kill_qstorno_ret_qs2qd 1144183 1144183 0 0.00 0 24108 35 0 0
sp_kill_qty_storno 603602 603602 0 0.00 0 24113 75 0 0
sp_lock_selected_doc 21088 21088 0 0.00 0 4917 3 0 0
sp_make_cost_storno 28529 28529 0 0.00 0 12469 45 0 0
sp_multiply_rows_for_pdistr 28529 28529 0 0.00 0 12984 23 0 0
sp_multiply_rows_for_qdistr 20907 20907 0 0.00 0 13699 165 0 0
sp_pay_from_customer 7366 7366 0 0.00 4 15007 95 0 0
sp_pay_to_supplier 5909 5909 0 0.00 4 12995 110 0 0
sp_payment_common 13275 13275 0 0.00 1 14979 21 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 15962 324 0 0
sp_qd_handle_on_invoice_upd_sts 10756 10756 0 0.00 0 19147 321 0 0
sp_qd_handle_on_reserve_upd_sts 10252 10252 0 0.00 0 9461 57 0 0
sp_reserve_write_off 10252 10252 0 0.00 6 10686 163 0 0
sp_supplier_invoice 5521 5521 0 0.00 6 86413 3426 0 0
sp_supplier_order 5602 5602 0 0.00 5 45616 1261 0 0
srv_aggregate_perf_data 97 97 0 0.00 3011 650265 23575 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2668 125924 44739 0 0
srv_find_qd_qs_mism 1064792 1064792 0 0.00 0 15517 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 35 53322 1116 0 0
srv_make_money_saldo 802 802 0 0.00 1 10783 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 233418 493978 329677 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 0 12012 161 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4170 79 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 1830 9 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 840 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 3651 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 8845 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 410 9 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 16850 1546 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 5050 10 0 0
t$perf-norm:sp_client_order 20907 20907 0 0.00 0 3644 15 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 5780 9 0 0
t$perf-norm:sp_pay_from_customer 7366 7366 0 0.00 0 10051 11 0 0
t$perf-norm:sp_pay_to_supplier 5909 5909 0 0.00 0 2807 8 0 0
t$perf-norm:sp_reserve_write_off 10252 10252 0 0.00 0 7267 12 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 3499 16 0 0
t$perf-norm:sp_supplier_order 5602 5602 0 0.00 0 4741 14 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 83 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 212 4 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 3158 21 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 1063 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10210 10004 206 2.01 0 6171 20 0 206
v_all_customers 38518 38518 0 0.00 0 28 1 0 0
v_all_suppliers 31022 31022 0 0.00 0 26 1 0 0
v_all_wares 41814 41814 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 3565 15 0 0
v_cancel_client_order 3606 3606 0 0.00 0 4796 9 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 6930 16 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 696 5 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 4796 13 0 0
v_cancel_supplier_invoice 832 820 12 1.44 0 3668 25 0 12
v_cancel_supplier_order 828 828 0 0.00 0 441 4 0 0
v_cancel_write_off 1984 1984 0 0.00 0 5774 18 0 0
v_max_id_clo_ord 5602 5602 0 0.00 0 228 1 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 2006 11 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 3144 4 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 1788 3 0 0
v_max_non_paid_realizn 3880 3880 0 0.00 0 500 3 0 0
v_min_id_clo_ord 5602 5602 0 0.00 0 3013 8 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 5202 17 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 9461 8 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 4489 12 0 0
v_min_non_paid_realizn 7366 7366 0 0.00 0 5233 15 0 0
v_our_firm 10268 10268 0 0.00 0 29 1 0 0
v_reserve_write_off 20504 20504 0 0.00 0 7635 9 0 0
x_lock_depdocs_on_canc_invoice 11721 11721 0 0.00 0 11441 117 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 5553 338 0 0
x_make_qstorno_1000_1200 5602 5602 0 0.00 1 45608 1183 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 3 86367 3279 0 0
x_make_qstorno_2100_3300 460864 460864 0 0.00 0 40705 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 22:39:36 to 28-02-2023 22:39:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:44 4 8192000000 8806540928 8820465664 11753632 0.143 3 3 4 0 11937536 76800 107104
20:09:45 19074 8192000000 9900681696 10210590720 988474304 10.767 100 86 172 0 1016740704 5923312 22343088
20:11:07 25719 8192000000 9952870848 10274062336 1034331984 11.210 100 91 182 0 1065717792 8429952 22955856
20:12:36 13035 8192000000 9984526688 10315476992 1067765360 11.531 100 90 180 0 1100263328 10222192 22275776
20:13:55 8705 8192000000 10019030096 10365530112 1108171104 11.915 100 76 152 0 1136287104 10209184 17906816
20:15:07 4753 8192000000 10073965920 10433167360 1162835504 12.430 100 74 146 0 1190985216 11489376 16660336
20:16:15 2041 8192000000 10123383472 10487922688 1208506352 12.855 100 85 169 0 1240376736 13125584 18744800
20:17:18 2306 8192000000 10165934864 10537512960 1248430576 13.224 100 95 188 1 1283199024 14191056 20577392
20:18:22 3266 8192000000 10188626496 10571911168 1276056080 13.477 100 75 150 0 1305301968 12639536 16606352
20:19:26 1117 8192000000 10214440752 10598694912 1296526752 13.664 100 94 189 0 1330720240 13783664 20409824
20:20:29 1534 8192000000 10215125232 10607251456 1303192096 13.724 100 81 160 0 1333169024 11769488 18207440
20:21:31 3465 8192000000 10204734160 10610790400 1310158848 13.788 100 35 70 0 1325228656 7146224 7923584
20:22:35 7244 8192000000 10213066304 10621767680 1320461856 13.881 100 27 55 1 1332867872 5691808 6714208
20:23:42 12668 8192000000 10218704304 10634764288 1332077280 13.986 100 31 61 1 1349364960 9632928 7654752
20:24:57 18736 8192000000 10222267888 10644025344 1334431792 14.007 100 36 72 1 1349048336 7095872 7520672
20:26:20 5761 8192000000 10231820864 10650443776 1340937232 14.066 100 24 49 1 1351944336 5903296 5103808
20:27:26 10057 8192000000 10221363392 10636283904 1307454256 13.763 98 23 47 0 1317576864 5203984 4918624
20:28:40 53519 8192000000 10201014240 10616426496 1260315504 13.333 95 17 34 0 1269753888 5153392 4284992
20:30:36 6163 8192000000 10146980240 10540929024 1220305680 12.965 96 31 62 1 1234633376 7229408 7098288
20:31:43 7996 8192000000 10132871392 10531033088 1214158512 12.908 97 36 73 3 1229121568 6258576 8704480
20:32:52 17040 8192000000 10156403024 10550824960 1245827440 13.200 99 25 48 0 1256936496 5303632 5805424
20:34:53 7831 8192000000 10172327024 10579836928 1281159808 13.524 100 20 40 0 1291629648 5651456 4818384
20:36:02 7805 8192000000 10188902384 10600742912 1299412000 13.690 100 19 37 1 1309552496 5917664 4222832
20:37:13 7660 8192000000 10196841312 10610483200 1302840256 13.721 100 18 34 1 1316453552 10012272 3601024
20:38:25 67847 8192000000 10232241056 10639450112 1323658208 13.910 100 40 78 0 1338249136 5664864 8926064
20:40:44 10359 8192000000 10254158720 10663804928 1343071616 14.085 100 53 103 1 1366132368 11540880 11519872
20:41:59 8796 8192000000 10275284368 10690420736 1363269120 14.267 100 53 102 1 1387387040 12616736 11501184
20:43:10 24266 8192000000 10276598208 10699595776 1370780912 14.334 100 41 81 1 1395624096 15437056 9406128
20:44:38 29874 8192000000 10315263840 10736476160 1391763408 14.522 100 41 82 0 1419475872 17164112 10548352
20:46:15 19734 8192000000 10319882800 10746945536 1400637024 14.601 100 36 72 1 1437682848 27724048 9321776
20:47:49 8799 8192000000 10346539760 10767974400 1419303984 14.767 100 53 108 2 1457727712 25798448 12625280
20:49:01 3050 8192000000 10354865712 10774028288 1419739968 14.770 100 66 132 1 1465452320 29068128 16644224
20:50:09 16692 8192000000 10356826496 10782130176 1419735504 14.770 100 63 124 1 1469691824 34109616 15846704
20:51:33 2684 8192000000 10362331456 10777591808 1421618384 14.787 100 75 149 2 1468041520 28027920 18395216
20:52:42 10939 8192000000 10349862992 10771922944 1412535312 14.706 100 65 130 0 1458055200 29636512 15883376
20:53:59 3439 8192000000 10352648560 10760761344 1397965840 14.577 100 74 146 0 1451236208 35109600 18160768
20:55:08 2668 8192000000 10352825952 10763038720 1393899328 14.541 100 70 140 0 1456038800 44419872 17719600
20:56:16 8641 8192000000 10357796240 10773811200 1395757760 14.557 100 68 132 1 1463969616 50713296 17498560
20:57:34 3340 8192000000 10339190000 10758086656 1380536688 14.421 100 59 118 1 1447351216 51800160 15014368
20:58:43 3769 8192000000 10342099360 10746740736 1363915200 14.272 99 66 132 0 1437531344 56644656 16971488
20:59:49 11347 8192000000 10328308128 10750431232 1375811232 14.379 99 48 92 1 1425337120 37170800 12355088
21:01:33 3450 8192000000 10331911360 10750377984 1384955712 14.461 100 58 112 2 1437927344 38062448 14909184
21:02:41 18648 8192000000 10344639392 10772725760 1370534720 14.332 98 40 80 0 1434388816 52289296 11564800
21:04:05 22698 8192000000 10337281696 10769616896 1383670560 14.449 100 30 58 0 1441679184 49695200 8313424
21:05:30 23639 8192000000 10316895888 10761818112 1369338768 14.321 100 23 46 0 1429892768 53656096 6897904
21:07:02 46535 8192000000 10309945424 10757197824 1352754528 14.172 99 18 36 1 1416734880 58166656 5813696
21:09:34 91465 8192000000 10256195504 10703515648 1343553424 14.089 99 25 49 0 1358516640 9174880 5788336
21:12:13 28930 8192000000 10265223680 10716631040 1369863504 14.326 100 16 32 0 1381713600 7865936 3984160
21:13:46 69506 8192000000 10281973360 10729644032 1371598384 14.341 99 8 16 0 1380879520 7931056 1350080
21:16:13 73226 8192000000 10286346768 10736238592 1375405456 14.375 99 31 59 1 1393936416 11278624 7252336
21:18:29 22258 8192000000 10285038960 10728062976 1379218800 14.410 100 36 71 0 1396294128 8177792 8897536
21:19:53 71243 8192000000 10302108032 10751078400 1391619168 14.520 100 23 44 0 1408421072 11820512 4981392
21:22:16 125903 8192000000 10307919664 10772062208 1386859232 14.478 99 22 44 0 1406802912 14370304 5573376
21:25:23 63750 8192000000 10288602448 10743607296 1378341760 14.402 99 17 34 0 1392003616 9895312 3766544
21:27:28 13493 8192000000 10299033200 10746601472 1392615344 14.529 100 39 78 0 1411029856 9830592 8583920
21:28:47 72814 8192000000 10298413472 10768084992 1397184704 14.570 100 12 24 0 1412684320 13152784 2346832
21:31:00 37688 8192000000 10279803728 10740486144 1377022336 14.390 99 15 30 0 1387848016 7582304 3243376
21:32:42 91115 8192000000 10288861888 10760601600 1396144752 14.561 100 19 39 0 1407527424 6496656 4886016
21:35:18 27347 8192000000 10277383536 10747998208 1371533488 14.341 98 11 22 0 1384581536 10070864 2977184
21:36:56 66385 8192000000 10298672560 10772320256 1388566144 14.493 99 15 30 0 1404539408 12082432 3890832
21:39:03 75576 8192000000 10292761296 10757025792 1394544864 14.546 100 12 24 0 1408433392 11442624 2445904
21:41:19 57391 8192000000 10313378752 10771910656 1378982800 14.407 98 15 30 0 1392616032 10972720 2660512
21:43:18 61998 8192000000 10321038016 10791436288 1408525744 14.671 100 19 39 0 1429104352 16016000 4562608
21:45:48 37393 8192000000 10285444528 10736840704 1339678800 14.055 97 28 56 2 1361596160 15875472 6041888
21:47:26 116675 8192000000 10266998320 10726043648 1281788832 13.529 92 20 40 0 1302887200 16675488 4422880
21:50:23 58286 8192000000 10276230544 10709229568 1283126992 13.542 94 31 58 0 1299158080 9610688 6420400
21:52:22 58596 8192000000 10258251568 10699583488 1349600192 14.144 100 34 68 0 1365691280 8261840 7829248
21:54:25 13912 8192000000 10263979712 10699173888 1339485792 14.053 99 42 84 0 1358185200 9581088 9118320
21:55:45 55269 8192000000 10227441104 10676625408 1292100224 13.623 96 17 35 0 1303813760 7280944 4432592
21:57:41 69953 8192000000 10237336336 10688184320 1330833952 13.975 98 9 18 0 1347347328 14359120 2154256
22:01:00 90788 8192000000 10250380544 10713161728 1322867840 13.903 97 9 18 1 1333345824 8740000 1737984
22:03:37 53153 8192000000 10306517200 10756661248 1381010848 14.426 100 14 28 0 1399979920 15877808 3091264
22:05:38 57813 8192000000 10277527472 10743476224 1363765936 14.271 99 17 35 1 1390696560 22488512 4442112
22:07:43 36572 8192000000 10285393472 10720354304 1343847856 14.092 98 36 70 3 1360729568 9851600 7030112
22:09:20 31742 8192000000 10295532480 10750152704 1378819136 14.406 100 36 73 0 1400317440 13790176 7708128
22:10:54 38082 8192000000 10298656720 10752204800 1371705648 14.342 99 23 46 0 1389774176 13207280 4861248
22:12:32 51244 8192000000 10305629840 10744754176 1371483472 14.340 99 33 61 0 1392060992 12654144 7923376
22:14:26 109035 8192000000 10288986224 10756100096 1366325200 14.294 98 17 34 0 1381012464 11151600 3535664
22:18:13 52057 8192000000 10317923472 10768687104 1405354928 14.643 100 28 56 0 1418884128 7165600 6363600
22:20:24 59261 8192000000 10300519472 10763988992 1384922512 14.461 98 12 24 0 1393509488 6326944 2260032
22:22:26 60984 8192000000 10318432848 10776776704 1392604496 14.529 98 19 38 0 1408065008 10736768 4723744
22:25:29 70046 8192000000 10303364496 10757869568 1386054400 14.471 99 25 48 2 1408731872 16723696 5953776
22:27:40 115907 8192000000 10298890560 10761732096 1388878384 14.496 99 14 27 0 1406223072 14361680 2983008
22:30:36 65739 8192000000 10284560816 10741256192 1340231616 14.059 96 20 40 0 1360043312 15086448 4725248
22:32:44 54439 8192000000 10284790048 10740613120 1365142112 14.283 99 27 52 0 1385792416 14708880 5941424
22:34:45 35412 8192000000 10289232880 10732625920 1348425536 14.133 98 31 62 0 1369914944 14648544 6840864
22:36:25 44038 8192000000 10272644560 10712502272 1333759200 14.001 98 31 62 0 1352079232 11474192 6845840
22:38:29 25417 8192000000 9089784912 9319067648 242888928 2.879 18 3 6 0 252954240 9699536 365776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-02-2023 22:39:42 to 28-02-2023 22:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-02-2023 22:39:58 to 28-02-2023 22:40:00.

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          244298
MON$OLDEST_ACTIVE               244299
MON$OLDEST_SNAPSHOT             244299
MON$NEXT_TRANSACTION            244299
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                       2043808
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:788: 
:789: mscs-test-161.budzdorov.ru    Tue Feb 28 22:39:00 2023
:790:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:791: 
:792: 
:793: mscs-test-161.budzdorov.ru    Tue Feb 28 22:39:00 2023
:794:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:795: 
--- end of diff output ---

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

28.02.23 22:40:01. 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-02-28 22:40:04 No severe PSQL-related problems occured
28.02.23 22:40:05. End of report.