Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.2023 04: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 15278288 kB
MemAvailable 30426268 kB
Buffers 20 kB
Cached 16394692 kB
SwapCached 3140 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-19 04:37:27
DTS_END                         2023-02-19 04:37:27.8490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39478
TRN_ID                          207445
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: 8464.32 21130 1015718
customer order: creation 154.38 473 18526
customer order: refuse 13.23 412 1588
order to supplier: creation 40.78 1402 4894
order to supplier: removal 3.16 167936 379
invoice (draft): creation 42.20 3368 5064
invoice (draft): removal 48.11 1037 5773
invoice accept: apply 38.13 28716 4576
invoice accept: cancel 43.07 1125 5168
customer reserve: creation 3600.02 271 432002
customer reserve: removal 4213.09 98 505571
realization accept: apply 74.78 269 8973
realization accept: cancel 76.47 159 9176
payment from customer: creation 53.77 157 6452
payment from customer: removal 5.38 114 645
payment to supplier: creation 43.07 180 5168
payment to supplier: removal 2.98 150 358
service: total inventory turnovers 3.88 3918 465
service: total monetary turnovers 6.86 341 823
service: aggregate perf. data 0.93 14304 112
service: refresh index statistics 0.04 198160 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-02-2023 04:39:30 to 19-02-2023 04:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3396
TEST_TIME 2 4587
TEST_TIME 3 4827
TEST_TIME 4 4823
TEST_TIME 5 4921
TEST_TIME 6 4874
TEST_TIME 7 4789
TEST_TIME 8 4759
TEST_TIME 9 5370
TEST_TIME 10 5458
TEST_TIME 11 5652
TEST_TIME 12 5888
TEST_TIME 13 6333
TEST_TIME 14 6422
TEST_TIME 15 6590
TEST_TIME 16 6714
TEST_TIME 17 6936
TEST_TIME 18 7303
TEST_TIME 19 7291
TEST_TIME 20 7518
TEST_TIME 21 8044
TEST_TIME 22 8199
TEST_TIME 23 8570
TEST_TIME 24 8350
TEST_TIME 25 8428
TEST_TIME 26 8726
TEST_TIME 27 9183
TEST_TIME 28 9412
TEST_TIME 29 9391
TEST_TIME 30 9595
TEST_TIME 31 10164
TEST_TIME 32 10856
TEST_TIME 33 10689
TEST_TIME 34 10434
TEST_TIME 35 10491
TEST_TIME 36 10395
TEST_TIME 37 10317
TEST_TIME 38 10194
TEST_TIME 39 9991
TEST_TIME 40 9804
TEST_TIME 41 9774
TEST_TIME 42 9744
TEST_TIME 43 9616
TEST_TIME 44 9578
TEST_TIME 45 9523
TEST_TIME 46 9337
TEST_TIME 47 9288
TEST_TIME 48 9268
TEST_TIME 49 9143
TEST_TIME 50 9071
TEST_TIME 51 9016
TEST_TIME 52 8951
TEST_TIME 53 8880
TEST_TIME 54 8961
TEST_TIME 55 8921
TEST_TIME 56 8779
TEST_TIME 57 8819
TEST_TIME 58 8840
TEST_TIME 59 8776
TEST_TIME 60 8863
TEST_TIME 61 8853
TEST_TIME 62 8859
TEST_TIME 63 8837
TEST_TIME 64 8859
TEST_TIME 65 8904
TEST_TIME 66 9001
TEST_TIME 67 8954
TEST_TIME 68 9016
TEST_TIME 69 9045
TEST_TIME 70 9063
TEST_TIME 71 9015
TEST_TIME 72 8993
TEST_TIME 73 8950
TEST_TIME 74 8890
TEST_TIME 75 8817
TEST_TIME 76 8770
TEST_TIME 77 8739
TEST_TIME 78 8703
TEST_TIME 79 8634
TEST_TIME 80 8589
TEST_TIME 81 8633
TEST_TIME 82 8640
TEST_TIME 83 8707
TEST_TIME 84 8735
TEST_TIME 85 8760
TEST_TIME 86 8743
TEST_TIME 87 8766
TEST_TIME 88 8736
TEST_TIME 89 8767
TEST_TIME 90 8753
TEST_TIME 91 8847
TEST_TIME 92 8838
TEST_TIME 93 8818
TEST_TIME 94 8751
TEST_TIME 95 8709
TEST_TIME 96 8712
TEST_TIME 97 8668
TEST_TIME 98 8618
TEST_TIME 99 8589
TEST_TIME 100 8593
TEST_TIME 101 8573
TEST_TIME 102 8556
TEST_TIME 103 8581
TEST_TIME 104 8564
TEST_TIME 105 8621
TEST_TIME 106 8607
TEST_TIME 107 8629
TEST_TIME 108 8677
TEST_TIME 109 8679
TEST_TIME 110 8689
TEST_TIME 111 8726
TEST_TIME 112 8743
TEST_TIME 113 8695
TEST_TIME 114 8644
TEST_TIME 115 8628
TEST_TIME 116 8623
TEST_TIME 117 8567
TEST_TIME 118 8569
TEST_TIME 119 8545
TEST_TIME 120 8486

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-02-2023 04:39:33 to 19-02-2023 04:39:37.

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 1413526 1413526 0 0.00 0 21877 13 0 0
doc_list_biud 1413515 1413515 0 0.00 0 54878 41 0 0
sp_add_invoice_to_stock 4648 4576 72 1.54 20 246505 28717 0 72
sp_cancel_adding_invoice 5168 5168 0 0.00 6 72278 1126 0 0
sp_cancel_client_order 1588 1588 0 0.00 6 23667 413 0 0
sp_cancel_customer_reserve 505571 505571 0 0.00 1 54923 98 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 4 7892 114 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 3 5640 150 0 0
sp_cancel_supplier_invoice 5779 5773 6 0.10 2 29083 1037 0 6
sp_cancel_supplier_order 379 379 0 0.00 16 1177245 167937 0 0
sp_cancel_write_off 9176 9176 0 0.00 2 9033 159 0 0
sp_client_order 18526 18526 0 0.00 5 16274 474 0 0
sp_customer_reserve 432002 432002 0 0.00 0 50720 272 0 0
sp_fill_shopping_cart 28482 28482 0 0.00 1 24285 87 0 0
sp_fill_shopping_cart_clo_res 431961 431961 0 0.00 0 17130 21 0 0
sp_get_clo_for_invoice 4528 4528 0 0.00 0 47339 357 0 0
sp_kill_cost_storno 15347 15347 0 0.00 0 8636 73 0 0
sp_kill_qstorno_ret_qs2qd 1026470 1026470 0 0.00 0 54837 44 0 0
sp_kill_qty_storno 541155 541155 0 0.00 0 54876 99 0 0
sp_lock_selected_doc 18692 18692 0 0.00 0 8035 8 0 0
sp_make_cost_storno 25119 25119 0 0.00 0 10840 76 0 0
sp_multiply_rows_for_pdistr 25119 25119 0 0.00 0 8876 36 0 0
sp_multiply_rows_for_qdistr 18526 18526 0 0.00 0 10040 187 0 0
sp_pay_from_customer 6452 6452 0 0.00 5 12701 158 0 0
sp_pay_to_supplier 5168 5168 0 0.00 6 14395 180 0 0
sp_payment_common 11617 11617 0 0.00 2 8873 45 0 0
sp_qd_handle_on_cancel_clo 1588 1588 0 0.00 1 23657 333 0 0
sp_qd_handle_on_invoice_upd_sts 9695 9695 0 0.00 0 23709 494 0 0
sp_qd_handle_on_reserve_upd_sts 8973 8973 0 0.00 0 17208 66 0 0
sp_reserve_write_off 8973 8973 0 0.00 7 17260 270 0 0
sp_supplier_invoice 5064 5064 0 0.00 9 87131 3369 0 0
sp_supplier_order 4894 4894 0 0.00 7 29870 1402 0 0
srv_aggregate_perf_data 112 112 0 0.00 2139 195809 14305 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 879 104448 37953 0 0
srv_find_qd_qs_mism 971037 971037 0 0.00 0 21872 11 0 0
srv_make_invnt_saldo 465 465 0 0.00 53 113729 3919 0 0
srv_make_money_saldo 823 823 0 0.00 1 12334 342 0 0
srv_recalc_idx_stat 5 5 0 0.00 46768 369611 198160 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 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 4576 4576 0 0.00 1 10516 804 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 2 6073 355 0 0
t$perf-norm:sp_cancel_client_order 1588 1588 0 0.00 0 4665 26 0 0
t$perf-norm:sp_cancel_customer_reserve 562 562 0 0.00 0 3349 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 2242 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 235 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1056 22 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 98823 7037 0 0
t$perf-norm:sp_cancel_write_off 866 866 0 0.00 0 486 13 0 0
t$perf-norm:sp_client_order 18526 18526 0 0.00 0 10777 32 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 504 11 0 0
t$perf-norm:sp_pay_from_customer 6452 6452 0 0.00 0 9175 20 0 0
t$perf-norm:sp_pay_to_supplier 5168 5168 0 0.00 0 5048 20 0 0
t$perf-norm:sp_reserve_write_off 8973 8973 0 0.00 0 8943 23 0 0
t$perf-norm:sp_supplier_invoice 5064 5064 0 0.00 0 4461 27 0 0
t$perf-norm:sp_supplier_order 4894 4894 0 0.00 0 5159 28 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 315 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 556 17 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 4484 19 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 276 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 204 107 0 0
v_add_invoice_to_stock 9198 9054 144 1.56 0 5632 24 0 144
v_all_customers 33936 33936 0 0.00 0 140 3 0 0
v_all_suppliers 27688 27688 0 0.00 0 53 4 0 0
v_all_wares 37050 37050 0 0.00 0 142 4 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2523 17 0 0
v_cancel_client_order 3176 3176 0 0.00 0 2269 13 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 3888 17 0 0
v_cancel_customer_reserve 1124 1124 0 0.00 0 1820 15 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 3416 16 0 0
v_cancel_supplier_invoice 816 804 12 1.47 0 1927 21 0 12
v_cancel_supplier_order 758 758 0 0.00 0 1268 12 0 0
v_cancel_write_off 1732 1732 0 0.00 0 7877 30 0 0
v_max_id_clo_ord 4894 4894 0 0.00 0 1435 5 0 0
v_max_id_clo_res 941 941 0 0.00 0 2691 11 0 0
v_max_id_ord_sup 5063 5063 0 0.00 0 7613 13 0 0
v_max_non_paid_invoice 1279 1279 0 0.00 0 164 7 0 0
v_max_non_paid_realizn 3331 3331 0 0.00 0 2086 7 0 0
v_min_id_clo_ord 4894 4894 0 0.00 0 1981 9 0 0
v_min_id_clo_res 941 941 0 0.00 0 6728 21 0 0
v_min_id_ord_sup 5063 5063 0 0.00 0 5098 9 0 0
v_min_non_paid_invoice 5167 5167 0 0.00 0 8754 20 0 0
v_min_non_paid_realizn 6450 6450 0 0.00 0 8869 19 0 0
v_our_firm 9352 9352 0 0.00 0 70 4 0 0
v_reserve_write_off 17946 17946 0 0.00 0 3822 11 0 0
x_lock_depdocs_on_canc_invoice 10539 10539 0 0.00 0 14654 138 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 10542 401 0 0
x_make_qstorno_1000_1200 4894 4894 0 0.00 2 29854 1246 0 0
x_make_qstorno_1200_2000 5064 5064 0 0.00 4 86359 3108 0 0
x_make_qstorno_2100_3300 432002 432002 0 0.00 0 50704 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-02-2023 04:39:38 to 19-02-2023 04:39:42.

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
02:07:31 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:32 27605 8192000000 9731929584 10034339840 809044112 8.988 100 81 160 0 836246896 7192768 20010016
02:10:05 28913 8192000000 9766374016 10075226112 836751936 9.267 100 88 176 0 870782368 12964304 21066128
02:11:36 11480 8192000000 9812438800 10134351872 871249616 9.612 100 83 166 0 911594416 21006176 19338624
02:12:59 4444 8192000000 9866279840 10199343104 919906736 10.095 100 90 177 0 986131408 46466208 19758464
02:14:06 3752 8192000000 9930131088 10271629312 952030512 10.411 100 93 182 1 1046296768 74851008 19415248
02:15:12 2404 8192000000 9962047136 10315870208 989172864 10.773 100 90 173 0 1077579824 70540720 17866240
02:16:17 1179 8192000000 9962922704 10328453120 1018031152 11.053 100 82 155 2 1089873200 56837680 15004368
02:17:20 968 8192000000 10013649136 10383601664 1041850752 11.282 100 96 193 3 1135564016 74021968 19691296
02:18:22 958 8192000000 10042404128 10418438144 1051685536 11.377 100 96 191 0 1167218720 96659040 18874144
02:19:24 1134 8192000000 10041019584 10416324608 1064283248 11.497 100 89 173 0 1160876128 79012352 17580528
02:20:26 1120 8192000000 10063976688 10445643776 1067761728 11.531 100 80 154 2 1182748688 99733840 15253120
02:21:31 914 8192000000 10041492912 10421243904 1072567440 11.577 100 96 187 0 1160523568 68551344 19404784
02:22:33 970 8192000000 10046712656 10431877120 1079748720 11.645 100 96 180 0 1167925120 69547968 18628432
02:23:35 934 8192000000 10048596176 10440089600 1082538544 11.672 100 87 163 1 1172511312 73171920 16800848
02:24:37 1099 8192000000 10028385024 10421841920 1087354016 11.717 100 88 167 2 1152527232 47341376 17831840
02:25:39 1177 8192000000 10080941584 10465968128 1095613952 11.796 100 92 180 1 1201202688 87946720 17642016
02:26:41 971 8192000000 10073196144 10468556800 1097393920 11.813 100 90 179 2 1198137120 82355520 18387680
02:27:43 1082 8192000000 10062906432 10451656704 1097145728 11.811 100 90 171 0 1181942288 66263120 18533440
02:28:44 2122 8192000000 10037344000 10429267968 1076925968 11.618 99 76 140 2 1150288624 59668624 13694032
02:29:47 1712 8192000000 10014566112 10401083392 1057634368 11.434 98 71 133 1 1132502368 60772624 14095376
02:30:50 1113 8192000000 10021413904 10404614144 1068735792 11.540 99 91 177 0 1141702064 55413824 17552448
02:31:52 1080 8192000000 10001157952 10377293824 1042545168 11.289 99 91 171 2 1123407088 61915040 18946880
02:32:54 7198 8192000000 9988816704 10371616768 1037559680 11.241 98 61 118 0 1100810304 51389456 11861168
02:34:02 1253 8192000000 9978016832 10350473216 1020244144 11.074 100 80 154 0 1101821920 65780976 15796800
02:35:05 3178 8192000000 9970611104 10343018496 1014779120 11.022 100 67 125 4 1098206304 69230384 14196800
02:36:09 1173 8192000000 9977083696 10348670976 1022717408 11.098 100 82 162 1 1098214960 58736848 16760704
02:37:12 3595 8192000000 9974814400 10357354496 1044412672 11.307 99 56 104 1 1100111536 45563584 10135280
02:38:24 25422 8192000000 9999806240 10381119488 1049013360 11.351 99 64 121 2 1105339264 44286720 12039184
02:39:55 8721 8192000000 10045137248 10431111168 1077428224 11.623 100 69 138 1 1174357696 83231248 13698224
02:41:08 11947 8192000000 10081588928 10469888000 1092641680 11.768 100 75 137 0 1205920864 98260080 15019104
02:42:24 5010 8192000000 10131969696 10530885632 1118677440 12.014 100 59 118 1 1256450672 125128896 12644336
02:43:34 14639 8192000000 10160459264 10562183168 1133064160 12.150 100 63 121 0 1287859872 141846608 12949104
02:44:51 3057 8192000000 10223742304 10622193664 1135416752 12.172 100 74 140 0 1332049712 180563168 16069792
02:45:57 3358 8192000000 10242603104 10655875072 1154006592 12.347 100 86 166 1 1363299024 192029968 17262464
02:47:02 1849 8192000000 10271884272 10672537600 1162546512 12.427 100 80 157 3 1382780320 202286528 17947280
02:48:15 4337 8192000000 10322272352 10732851200 1174879008 12.542 100 81 155 2 1434761648 242088720 17793920
02:49:28 909 8192000000 10335013280 10735624192 1189178912 12.676 100 92 179 1 1443762624 232988640 21595072
02:50:31 870 8192000000 10362702240 10777047040 1184907936 12.636 100 95 187 1 1474745888 267362240 22475712
02:51:34 1045 8192000000 10411043728 10819993600 1190577632 12.689 100 91 178 0 1527062608 315209376 21275600
02:52:39 2017 8192000000 10434950032 10845786112 1192835808 12.710 100 89 170 1 1542019232 329543984 19639440
02:53:44 937 8192000000 10466087664 10880884736 1196567648 12.744 100 99 194 3 1574063840 354225760 23270432
02:54:46 1114 8192000000 10463553120 10908991488 1194350096 12.724 100 94 185 0 1570613904 353737328 22526480
02:55:49 1353 8192000000 10465815248 10900922368 1196565488 12.744 100 91 176 1 1577175184 359436432 21173264
02:56:53 1136 8192000000 10499203456 10957193216 1214782432 12.913 100 93 177 0 1604768368 369239456 20746480
02:57:57 2090 8192000000 10531333488 11005657088 1217065920 12.935 100 81 157 0 1635996128 398814448 20115760
02:59:01 5889 8192000000 10519575904 10949976064 1202000848 12.795 100 82 163 0 1620367744 398771696 19595200
03:00:12 16469 8192000000 10507771456 10982137856 1197325920 12.751 100 44 88 0 1610697712 401388016 11983776
03:01:48 9320 8192000000 10520884160 10953080832 1172809120 12.523 100 47 94 0 1621249552 436438448 12001984
03:03:00 10834 8192000000 10506209072 10976501760 1162094800 12.423 99 43 85 1 1600661792 426905888 11661104
03:04:12 23505 8192000000 10454657792 10969288704 1165407552 12.454 100 33 64 0 1569441776 394934048 9100176
03:05:49 21086 8192000000 10389283680 10872926208 1156965680 12.375 100 22 43 0 1505813616 343217648 5630288
03:07:34 26533 8192000000 10313787184 10833289216 1147994496 12.291 99 39 72 1 1383358992 226712352 8652144
03:09:10 14822 8192000000 10116444672 10590691328 1142584496 12.240 100 26 52 0 1236485472 88081120 5819856
03:10:26 8315 8192000000 10152102928 10573193216 1142255888 12.237 100 65 126 0 1232354432 76534832 13563712
03:11:38 34654 8192000000 10156115344 10597314560 1140421632 12.219 100 47 94 0 1246541600 96297456 9822512
03:13:16 84893 8192000000 10086999776 10610040832 1134751856 12.166 99 6 12 0 1205271120 69116224 1403040
03:15:42 89970 8192000000 10080401984 10542972928 1109144528 11.924 97 17 34 1 1145630592 33456288 3029776
03:18:18 104448 8192000000 10079084272 10566737920 1106063312 11.895 97 16 32 0 1177753568 68561744 3128512
03:21:08 87150 8192000000 10055028480 10508550144 1074082848 11.591 95 28 54 1 1119219968 39443168 5693952
03:23:53 3074 8192000000 10063769488 10494738432 1124378368 12.068 100 80 151 5 1167848368 25617744 17852256
03:24:57 102698 8192000000 10062460128 10530947072 1092682288 11.768 96 19 38 0 1132092176 35525072 3884816
03:27:41 36974 8192000000 10093872960 10545250304 1136540288 12.183 100 23 46 0 1200342992 58791488 5011216
03:29:28 15862 8192000000 10144361952 10582519808 1140698896 12.222 100 65 114 0 1248548352 95772160 12077296
03:30:47 64584 8192000000 10099429616 10591870976 1102317840 11.860 97 12 24 0 1203765280 98520384 2927056
03:32:54 56440 8192000000 10059618208 10506182656 1120835456 12.035 99 13 26 0 1167262512 43909056 2518000
03:34:59 51026 8192000000 10083373648 10586861568 1149234832 12.302 100 11 22 0 1222128160 70524288 2369040
03:36:50 64820 8192000000 10085750720 10545324032 1130625664 12.127 98 10 20 1 1183168192 50796768 1745760
03:39:03 24960 8192000000 10088826688 10527744000 1122662464 12.052 98 29 58 1 1167033520 38583472 5787584
03:40:30 42078 8192000000 10121728976 10563141632 1106738480 11.902 96 26 52 0 1187301840 75078576 5484784
03:42:18 53614 8192000000 10070550064 10585534464 1094770672 11.788 96 16 32 0 1176240624 78089824 3380128
03:44:14 62386 8192000000 10105398928 10552811520 1139975584 12.215 100 34 68 0 1219832976 72160592 7696800
03:46:17 71831 8192000000 10097272688 10543226880 1118447904 12.012 98 36 70 0 1156458560 30970704 7039952
03:48:32 13593 8192000000 10080669104 10508505088 1138854240 12.205 100 45 88 2 1182034672 34622544 8557888
03:49:47 9792 8192000000 10104240992 10525478912 1138625168 12.203 100 59 116 1 1201410528 51417552 11367808
03:50:59 84987 8192000000 10072686608 10517192704 1116720816 11.996 98 34 69 2 1171862368 48372928 6768624
03:53:24 21625 8192000000 10110466768 10541133824 1137093776 12.188 99 51 101 0 1196255872 48395280 10766816
03:54:54 54706 8192000000 10134248544 10604023808 1129407728 12.116 98 13 26 0 1218783632 86218224 3157680
03:56:49 60131 8192000000 10193843872 10636328960 1144119776 12.254 99 18 36 0 1275288160 126498752 4669632
03:58:52 101138 8192000000 10150495568 10675126272 1135203728 12.170 99 17 34 0 1291036912 151239488 4593696
04:01:34 76969 8192000000 10072488384 10503376896 1100234704 11.840 96 16 32 0 1135689200 32343040 3111456
04:03:53 26883 8192000000 10080731872 10520301568 1126928576 12.092 99 38 74 0 1191558032 56816384 7813072
04:05:22 27161 8192000000 10086091616 10508517376 1116514080 11.994 99 41 82 0 1172345168 47655440 8175648
04:06:52 25587 8192000000 10115428480 10539290624 1111544352 11.947 98 45 88 1 1181932112 61297536 9090224
04:08:23 50004 8192000000 10074439424 10563862528 1111747680 11.949 99 28 56 0 1217627056 100461536 5417840
04:10:14 12365 8192000000 10087312544 10498838528 1105299376 11.888 98 57 114 0 1156568000 39508032 11760592
04:11:27 86344 8192000000 10078301888 10502504448 1081820576 11.665 96 50 100 0 1166429264 74213648 10395040
04:14:03 91823 8192000000 10068811888 10531348480 1115695104 11.986 99 13 26 0 1170517088 51601376 3220608
04:16:39 75070 8192000000 10077561984 10556313600 1118735120 12.015 99 15 30 0 1199171984 77200704 3236160
04:18:55 28911 8192000000 10105122800 10541920256 1112950784 11.960 98 29 58 0 1190436272 71118528 6366960
04:20:31 39080 8192000000 10127488400 10572042240 1121045568 12.037 99 35 70 0 1207961616 79015488 7900560
04:22:17 79972 8192000000 10116871488 10604138496 1118221488 12.010 98 23 46 0 1238456256 114772656 5462112
04:24:39 94273 8192000000 10111593376 10569437184 1114865280 11.978 98 21 42 0 1192656800 72566112 5225408
04:27:25 54905 8192000000 10108131008 10575155200 1128670576 12.109 99 23 46 0 1213370048 80069104 4630368
04:29:21 41363 8192000000 10077223264 10502684672 1077837984 11.627 95 33 64 0 1113709920 29838848 6033088
04:31:03 78511 8192000000 10104699648 10546585600 1111248896 11.944 98 35 67 0 1190992496 72760368 6983232
04:33:22 85199 8192000000 10102148160 10578808832 1111356112 11.945 98 13 26 0 1176216704 62307664 2552928
04:36:06 57976 8192000000 10097521472 10543669248 1124516496 12.070 100 23 44 0 1169375152 39586128 5272528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-02-2023 04:39:44 to 19-02-2023 04:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 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 19-02-2023 04:40:01 to 19-02-2023 04:40:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          207922
MON$OLDEST_ACTIVE               207923
MON$OLDEST_SNAPSHOT             207923
MON$NEXT_TRANSACTION            207923
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-30 07:30:08.6030 GMT
MON$PAGES                       2035552
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2902 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2902 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:193: 
:194: mscs-test-161.budzdorov.ru    Sun Feb 19 04:37:54 2023
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
:197: 
:198: mscs-test-161.budzdorov.ru    Sun Feb 19 04:37:54 2023
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Sun Feb 19 04:37:54 2023
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Sun Feb 19 04:38:01 2023
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
--- end of diff output ---

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

19.02.23 04:40:05. There are 3 logs in /var/tmp/logs-oltp40 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-19 04:40:07 No severe PSQL-related problems occured
19.02.23 04:40:09. End of report.