Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.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 15290528 kB
MemAvailable 30404620 kB
Buffers 0 kB
Cached 16396516 kB
SwapCached 3168 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-03-14 01:38:41
DTS_END                         2023-03-14 01:38:41.2470
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/37258
TRN_ID                          233608
ATT_ID                          652
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: 8970.35 22352 1076442
customer order: creation 163.19 382 19583
customer order: refuse 13.78 349 1654
order to supplier: creation 42.96 1276 5155
order to supplier: removal 3.20 116437 384
invoice (draft): creation 43.63 3250 5235
invoice (draft): removal 52.31 767 6277
invoice accept: apply 39.25 18309 4710
invoice accept: cancel 47.29 823 5675
customer reserve: creation 3612.14 181 433457
customer reserve: removal 4668.60 64 560232
realization accept: apply 78.73 196 9448
realization accept: cancel 83.86 110 10063
payment from customer: creation 56.01 126 6721
payment from customer: removal 5.13 105 616
payment to supplier: creation 45.96 184 5515
payment to supplier: removal 3.13 96 375
service: total inventory turnovers 4.37 1550 524
service: total monetary turnovers 6.02 44 722
service: aggregate perf. data 0.77 25482 92
service: refresh index statistics 0.03 277300 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2023 01:39:42 to 14-03-2023 01:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3589
TEST_TIME 2 2055
TEST_TIME 3 3410
TEST_TIME 4 3649
TEST_TIME 5 3983
TEST_TIME 6 3999
TEST_TIME 7 4407
TEST_TIME 8 4581
TEST_TIME 9 4998
TEST_TIME 10 4979
TEST_TIME 11 5166
TEST_TIME 12 5398
TEST_TIME 13 5651
TEST_TIME 14 5679
TEST_TIME 15 5906
TEST_TIME 16 6193
TEST_TIME 17 6526
TEST_TIME 18 6409
TEST_TIME 19 6332
TEST_TIME 20 6593
TEST_TIME 21 7002
TEST_TIME 22 7195
TEST_TIME 23 7497
TEST_TIME 24 7783
TEST_TIME 25 7857
TEST_TIME 26 8418
TEST_TIME 27 8612
TEST_TIME 28 9079
TEST_TIME 29 9972
TEST_TIME 30 10731
TEST_TIME 31 10551
TEST_TIME 32 10360
TEST_TIME 33 10274
TEST_TIME 34 10064
TEST_TIME 35 9800
TEST_TIME 36 9766
TEST_TIME 37 9649
TEST_TIME 38 9493
TEST_TIME 39 9413
TEST_TIME 40 9265
TEST_TIME 41 9181
TEST_TIME 42 9121
TEST_TIME 43 8990
TEST_TIME 44 8891
TEST_TIME 45 8874
TEST_TIME 46 9187
TEST_TIME 47 9046
TEST_TIME 48 8935
TEST_TIME 49 8877
TEST_TIME 50 8771
TEST_TIME 51 8918
TEST_TIME 52 8816
TEST_TIME 53 8821
TEST_TIME 54 8733
TEST_TIME 55 8673
TEST_TIME 56 8644
TEST_TIME 57 8590
TEST_TIME 58 8591
TEST_TIME 59 8465
TEST_TIME 60 8659
TEST_TIME 61 8574
TEST_TIME 62 8671
TEST_TIME 63 8647
TEST_TIME 64 8641
TEST_TIME 65 8661
TEST_TIME 66 8737
TEST_TIME 67 8751
TEST_TIME 68 8756
TEST_TIME 69 8780
TEST_TIME 70 8779
TEST_TIME 71 8862
TEST_TIME 72 8859
TEST_TIME 73 8801
TEST_TIME 74 8921
TEST_TIME 75 8895
TEST_TIME 76 8842
TEST_TIME 77 8799
TEST_TIME 78 8836
TEST_TIME 79 8768
TEST_TIME 80 8775
TEST_TIME 81 8750
TEST_TIME 82 8731
TEST_TIME 83 8699
TEST_TIME 84 8700
TEST_TIME 85 8641
TEST_TIME 86 8745
TEST_TIME 87 8795
TEST_TIME 88 8755
TEST_TIME 89 8847
TEST_TIME 90 8818
TEST_TIME 91 8939
TEST_TIME 92 8950
TEST_TIME 93 8965
TEST_TIME 94 9011
TEST_TIME 95 9122
TEST_TIME 96 9080
TEST_TIME 97 9097
TEST_TIME 98 9180
TEST_TIME 99 9121
TEST_TIME 100 9045
TEST_TIME 101 9039
TEST_TIME 102 8970
TEST_TIME 103 8996
TEST_TIME 104 8999
TEST_TIME 105 8992
TEST_TIME 106 8981
TEST_TIME 107 8933
TEST_TIME 108 8894
TEST_TIME 109 8871
TEST_TIME 110 8930
TEST_TIME 111 8897
TEST_TIME 112 8885
TEST_TIME 113 8916
TEST_TIME 114 8908
TEST_TIME 115 8941
TEST_TIME 116 8924
TEST_TIME 117 8936
TEST_TIME 118 8964
TEST_TIME 119 8970
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-03-2023 01:39:45 to 14-03-2023 01:39:49.

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 1478917 1478917 0 0.00 0 47998 6 0 0
doc_list_biud 1478910 1478910 0 0.00 0 25904 30 0 0
sp_add_invoice_to_stock 4798 4710 88 1.83 33 169736 18310 0 88
sp_cancel_adding_invoice 5675 5675 0 0.00 3 73538 824 0 0
sp_cancel_client_order 1654 1654 0 0.00 4 7671 349 0 0
sp_cancel_customer_reserve 560232 560232 0 0.00 1 25906 64 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 2 10343 105 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 5609 97 0 0
sp_cancel_supplier_invoice 6286 6277 9 0.14 1 17403 767 0 9
sp_cancel_supplier_order 384 384 0 0.00 6 1219301 116437 0 0
sp_cancel_write_off 10063 10063 0 0.00 1 7876 111 0 0
sp_client_order 19583 19583 0 0.00 3 30033 383 0 0
sp_customer_reserve 433457 433457 0 0.00 0 47562 182 0 0
sp_fill_shopping_cart 29969 29969 0 0.00 1 16596 43 0 0
sp_fill_shopping_cart_clo_res 433452 433452 0 0.00 0 17560 13 0 0
sp_get_clo_for_invoice 4705 4705 0 0.00 0 26669 316 0 0
sp_kill_cost_storno 16729 16729 0 0.00 0 23379 61 0 0
sp_kill_qstorno_ret_qs2qd 1137188 1137188 0 0.00 0 25888 34 0 0
sp_kill_qty_storno 598438 598438 0 0.00 0 25904 73 0 0
sp_lock_selected_doc 19548 19548 0 0.00 0 8117 6 0 0
sp_make_cost_storno 26389 26389 0 0.00 0 46646 68 0 0
sp_multiply_rows_for_pdistr 26389 26389 0 0.00 0 7740 30 0 0
sp_multiply_rows_for_qdistr 19583 19583 0 0.00 0 19594 151 0 0
sp_pay_from_customer 6721 6721 0 0.00 4 17123 127 0 0
sp_pay_to_supplier 5515 5515 0 0.00 4 49728 185 0 0
sp_payment_common 12236 12236 0 0.00 1 13152 42 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 0 7661 317 0 0
sp_qd_handle_on_invoice_upd_sts 10380 10380 0 0.00 0 22369 327 0 0
sp_qd_handle_on_reserve_upd_sts 9448 9448 0 0.00 0 23969 67 0 0
sp_reserve_write_off 9448 9448 0 0.00 6 24341 197 0 0
sp_supplier_invoice 5235 5235 0 0.00 6 88848 3250 0 0
sp_supplier_order 5155 5155 0 0.00 4 44965 1277 0 0
srv_aggregate_perf_data 92 92 0 0.00 2847 466955 25482 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1419 126463 49737 0 0
srv_find_qd_qs_mism 1032065 1032065 0 0.00 0 21044 5 0 0
srv_make_invnt_saldo 524 524 0 0.00 56 187951 1550 0 0
srv_make_money_saldo 722 722 0 0.00 1 8648 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 103218 423662 277300 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4710 4710 0 0.00 0 22773 193 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5441 119 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 12656 28 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 7093 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 2346 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 529 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 1008 23 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 34394 1545 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 9246 15 0 0
t$perf-norm:sp_client_order 19583 19583 0 0.00 0 12667 20 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 4567 27 0 0
t$perf-norm:sp_pay_from_customer 6721 6721 0 0.00 0 11439 11 0 0
t$perf-norm:sp_pay_to_supplier 5515 5515 0 0.00 0 5631 10 0 0
t$perf-norm:sp_reserve_write_off 9448 9448 0 0.00 0 14871 16 0 0
t$perf-norm:sp_supplier_invoice 5235 5235 0 0.00 0 11866 28 0 0
t$perf-norm:sp_supplier_order 5155 5155 0 0.00 0 5330 16 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 100 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 246 4 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 1054 23 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 1785 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9586 9410 176 1.83 0 5592 19 0 176
v_all_customers 35500 35500 0 0.00 0 22 1 0 0
v_all_suppliers 28802 28802 0 0.00 0 22 1 0 0
v_all_wares 39166 39166 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2656 15 0 0
v_cancel_client_order 3308 3308 0 0.00 0 5300 8 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 4391 11 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 743 5 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 2065 7 0 0
v_cancel_supplier_invoice 740 722 18 2.43 0 1310 15 0 18
v_cancel_supplier_order 768 768 0 0.00 0 3492 16 0 0
v_cancel_write_off 1938 1938 0 0.00 0 3117 18 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 1003 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 1862 8 0 0
v_max_id_ord_sup 5232 5232 0 0.00 0 1767 2 0 0
v_max_non_paid_invoice 1500 1500 0 0.00 0 2525 5 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 8314 6 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 16505 16 0 0
v_min_id_clo_res 993 993 0 0.00 0 7990 22 0 0
v_min_id_ord_sup 5232 5232 0 0.00 0 15416 19 0 0
v_min_non_paid_invoice 5515 5515 0 0.00 0 12406 40 0 0
v_min_non_paid_realizn 6721 6721 0 0.00 0 13149 28 0 0
v_our_firm 9834 9834 0 0.00 0 20 1 0 0
v_reserve_write_off 18894 18894 0 0.00 0 4203 7 0 0
x_lock_depdocs_on_canc_invoice 11591 11591 0 0.00 0 16438 131 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 16782 491 0 0
x_make_qstorno_1000_1200 5155 5155 0 0.00 2 44952 1186 0 0
x_make_qstorno_1200_2000 5235 5235 0 0.00 2 82346 3103 0 0
x_make_qstorno_2100_3300 433457 433457 0 0.00 0 47560 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 01:39:50 to 14-03-2023 01:39:54.

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:45 2 8192000000 8797544336 8808202240 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:45 23744 8192000000 9915171136 10227834880 1001678320 10.895 100 88 176 0 1030825680 6475728 22671632
23:11:16 22966 8192000000 9956222064 10281500672 1040711424 11.272 100 88 176 0 1072196688 9048496 22436768
23:12:47 17525 8192000000 9995562384 10332540928 1079709344 11.645 100 83 165 0 1110350160 10347760 20293056
23:14:10 14587 8192000000 10040089952 10389213184 1121633664 12.042 100 75 150 0 1151158288 12062496 17462128
23:15:27 6150 8192000000 10078786032 10435706880 1165387440 12.454 100 84 168 0 1197329232 12628368 19313424
23:16:36 4393 8192000000 10115420320 10480758784 1201117776 12.787 100 84 168 0 1233115136 13580560 18416800
23:17:42 1872 8192000000 10154866832 10528702464 1244105968 13.184 100 73 146 0 1272016080 11910736 15999376
23:18:47 1334 8192000000 10193933840 10572419072 1277590864 13.491 100 91 182 0 1311515408 13575056 20349488
23:19:49 1355 8192000000 10209913008 10596335616 1297221056 13.670 100 80 160 0 1326874064 11593616 18059392
23:20:51 7653 8192000000 10193136784 10603483136 1299344864 13.689 100 25 50 0 1311329776 6696352 5288560
23:22:00 7489 8192000000 10204750208 10615537664 1317416368 13.853 100 25 50 1 1329672880 7247664 5008848
23:23:10 11672 8192000000 10209970416 10628878336 1318858576 13.866 100 20 40 0 1333491840 10126848 4506416
23:24:23 54647 8192000000 10237712176 10646196224 1326103488 13.932 100 28 54 1 1341742240 8575840 7062912
23:26:18 5893 8192000000 10220739360 10640433152 1317462480 13.854 99 20 41 0 1331584624 9785568 4336576
23:27:26 10337 8192000000 10213492672 10634784768 1315844992 13.839 99 17 33 1 1324704496 5641056 3218448
23:28:39 10097 8192000000 10194526704 10605621248 1280956752 13.522 98 14 26 0 1288965184 4863456 3144976
23:29:50 32675 8192000000 10163550144 10578485248 1254135376 13.276 97 29 58 1 1265876752 4493024 7248352
23:31:23 4014 8192000000 10134175200 10535120896 1236523904 13.114 99 17 34 0 1245742256 5636560 3581792
23:32:28 5331 8192000000 10146058512 10548047872 1255269984 13.287 100 19 36 1 1263954512 4914096 3770432
23:33:34 8946 8192000000 10147994128 10557489152 1246974816 13.210 98 22 44 0 1257751712 6257760 4519136
23:34:48 26210 8192000000 10166952288 10578468864 1278472752 13.499 100 14 28 0 1286774224 5243216 3058256
23:36:17 7544 8192000000 10186749680 10601086976 1297700272 13.674 100 33 66 0 1311514016 6116544 7697200
23:37:25 6184 8192000000 10206043888 10615922688 1305832992 13.748 100 34 69 0 1319753264 5252208 8668064
23:38:36 45357 8192000000 10228318272 10647085056 1334667760 14.009 100 29 58 0 1346771104 5487504 6615840
23:40:31 95370 8192000000 10249101296 10679058432 1348705152 14.136 100 30 60 0 1366588288 10985840 6897296
23:43:21 4628 8192000000 10284343792 10699964416 1365385744 14.286 100 55 111 1 1396216688 18492496 12338448
23:44:35 19598 8192000000 10282239584 10705940480 1373685232 14.360 100 39 78 0 1398437504 15202672 9549600
23:46:05 12906 8192000000 10296843664 10721554432 1384030816 14.453 100 42 84 0 1412846496 19513696 9301984
23:47:21 4265 8192000000 10318649904 10737152000 1392505952 14.528 100 51 102 1 1425894928 21973888 11415088
23:48:31 10317 8192000000 10304986592 10717831168 1376652208 14.387 100 69 133 2 1415128816 23581296 14895312
23:49:45 4542 8192000000 10314653376 10732453888 1373698720 14.360 99 53 105 0 1413074160 27101088 12274352
23:50:57 1419 8192000000 10338380368 10747707392 1388378800 14.491 99 80 159 1 1443000640 35053248 19568592
23:52:01 4939 8192000000 10334540896 10750980096 1393978784 14.541 100 60 120 1 1443466736 35313840 14174112
23:53:10 2334 8192000000 10350416960 10761195520 1385376480 14.465 99 78 157 0 1444856992 40464960 19015552
23:54:17 3886 8192000000 10337301952 10743312384 1377838144 14.397 100 73 142 1 1439519824 43898880 17782800
23:55:26 6278 8192000000 10345150800 10750844928 1385189584 14.463 100 75 146 2 1452417840 49768576 17459680
23:56:38 7860 8192000000 10337395808 10751995904 1387304496 14.482 100 53 104 0 1444060048 43033360 13722192
23:57:54 2676 8192000000 10358401776 10768703488 1382949568 14.443 99 60 120 0 1452816096 53915248 15951280
23:59:03 4254 8192000000 10368729072 10769436672 1387805424 14.486 100 75 150 0 1465891648 58388528 19697696
00:00:12 2098 8192000000 10373326864 10777235456 1394226288 14.544 100 84 167 2 1474603520 58840000 21537232
00:01:20 15009 8192000000 10347257024 10772987904 1390814288 14.513 100 32 64 0 1454373152 54416864 9142000
00:02:43 11146 8192000000 10340676848 10765524992 1382821904 14.442 100 34 68 0 1445879456 53854896 9202656
00:04:03 14743 8192000000 10354112912 10777120768 1379846608 14.415 100 43 79 2 1456451616 64738160 11866848
00:05:20 21743 8192000000 10342362304 10778537984 1375995024 14.381 100 32 64 0 1443638672 58725648 8918000
00:06:55 11022 8192000000 10342078208 10785062912 1379460912 14.412 100 25 50 0 1445930464 59479040 6990512
00:08:32 69154 8192000000 10295312064 10754293760 1335310896 14.015 97 10 20 0 1368031104 30448016 2272192
00:10:42 68727 8192000000 10276908048 10734469120 1365263808 14.285 99 14 29 0 1379851824 11865840 2722176
00:12:59 72710 8192000000 10273612144 10712604672 1351358752 14.160 98 30 60 0 1365136400 7008080 6769568
00:15:18 126424 8192000000 10250989664 10698080256 1278485472 13.499 93 11 22 0 1287389840 6835040 2069328
00:18:25 14675 8192000000 10262166848 10704809984 1359295152 14.231 100 25 53 0 1373227344 8654000 5278192
00:19:41 40045 8192000000 10275845936 10715734016 1338673664 14.045 97 39 74 1 1360553424 12925600 8954160
00:21:22 30566 8192000000 10299388256 10740191232 1383023808 14.444 100 39 78 0 1409627408 17595616 9007984
00:23:02 55243 8192000000 10302231504 10753429504 1382772832 14.441 100 37 74 0 1410993520 19379264 8841424
00:25:01 34102 8192000000 10271891984 10738196480 1371680976 14.342 100 2 4 0 1387052880 15057472 314432
00:26:36 86410 8192000000 10283107840 10745671680 1379031904 14.408 99 13 26 1 1392640320 10644096 2964320
00:29:50 121348 8192000000 10293172880 10765860864 1397362224 14.572 100 15 28 1 1414198000 14078416 2757360
00:32:59 61117 8192000000 10307099264 10774368256 1405869824 14.647 100 12 24 0 1424457616 15809248 2778544
00:35:01 72744 8192000000 10308073408 10768982016 1387015504 14.479 98 11 22 0 1405326912 15507328 2804080
00:37:19 79663 8192000000 10290111584 10771173376 1381840128 14.433 99 15 30 0 1400515936 15130048 3545760
00:40:49 60148 8192000000 10292078112 10738741248 1355955776 14.201 97 24 49 1 1367425552 5973728 5496048
00:42:52 31729 8192000000 10289898704 10737192960 1356294784 14.204 98 39 76 1 1375171824 10843072 8033968
00:44:38 72007 8192000000 10280606960 10733748224 1344591120 14.099 97 13 26 1 1366775360 19175344 3008896
00:46:52 114898 8192000000 10269996000 10725863424 1302441136 13.717 94 13 24 0 1315239904 9542848 3255920
00:49:47 85665 8192000000 10291350752 10746585088 1368430144 14.313 99 15 30 0 1385147104 13649280 3067680
00:52:17 62418 8192000000 10276310688 10713866240 1333590944 14.000 97 19 38 0 1356464304 18910704 3962656
00:54:20 116511 8192000000 10278601456 10728402944 1352778832 14.172 98 28 56 0 1371262800 11548832 6935136
00:57:18 65497 8192000000 10259521552 10706345984 1345278208 14.105 98 29 58 0 1364429856 12184224 6967424
00:59:27 65900 8192000000 10273521728 10727198720 1364249392 14.275 99 15 28 0 1377987040 10206656 3530992
01:01:33 32127 8192000000 10286242448 10722648064 1352349728 14.169 98 34 69 1 1372551312 11813488 8388096
01:03:05 65516 8192000000 10275083168 10726244352 1370264400 14.329 100 15 30 0 1388155680 14134992 3756288
01:05:11 56272 8192000000 10278497696 10732138496 1349649936 14.144 98 13 27 0 1360419360 8222992 2546432
01:07:13 61394 8192000000 10271595584 10727665664 1332147024 13.987 97 26 52 0 1354947968 16554800 6246144
01:09:19 116439 8192000000 10269165440 10714877952 1235784640 13.107 90 6 12 0 1247886656 11225680 876336
01:12:48 100656 8192000000 10290248160 10717667328 1311670096 13.801 96 20 37 0 1332252992 16549712 4033184
01:15:40 122113 8192000000 10299346272 10755928064 1368787664 14.316 99 28 56 0 1396974208 21990736 6195808
01:18:54 79132 8192000000 10278071136 10734915584 1342267616 14.078 97 6 12 1 1353078352 9514320 1296416
01:21:26 74406 8192000000 10286306208 10766094336 1389719152 14.503 100 8 16 1 1409262208 17381616 2161440
01:23:41 37708 8192000000 10290602640 10754215936 1390686976 14.512 100 15 30 0 1401548096 7161232 3699888
01:25:24 87720 8192000000 10289293504 10748690432 1358024800 14.220 97 21 43 1 1372717600 9818928 4873872
01:27:54 77875 8192000000 10275422704 10743922688 1329170512 13.960 96 10 20 0 1344219360 12654384 2394464
01:30:13 63036 8192000000 10269564768 10741456896 1373947536 14.362 100 6 12 0 1389235136 13816736 1470864
01:32:21 53485 8192000000 10274357328 10727677952 1301186368 13.706 94 19 38 0 1313078896 8003120 3889408
01:34:17 64487 8192000000 10279236736 10730057728 1335645328 14.018 97 19 40 0 1351576896 12043408 3888160
01:36:21 39067 8192000000 10277477312 10737123328 1335514288 14.017 97 27 55 1 1356326064 15129584 5682192
01:38:09 38006 8192000000 9580825952 9927913472 720795456 8.087 53 18 36 0 734447136 9694832 3956848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-03-2023 01:39:56 to 14-03-2023 01:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 14-03-2023 01:40:12 to 14-03-2023 01:40:14.

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          233336
MON$OLDEST_ACTIVE               234205
MON$OLDEST_SNAPSHOT             234205
MON$NEXT_TRANSACTION            234205
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                       2029664
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:197: 
:198: mscs-test-161.budzdorov.ru    Tue Mar 14 01:38:52 2023
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Tue Mar 14 01:39:03 2023
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Tue Mar 14 01:39:03 2023
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Tue Mar 14 01:39:03 2023
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Mar 14 01:39:04 2023
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
--- end of diff output ---

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

14.03.23 01:40:15. 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-03-14 01:40:18 No severe PSQL-related problems occured
14.03.23 01:40:19. End of report.