Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2022 07:45
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 14799424 kB
MemAvailable 30052448 kB
Buffers 0 kB
Cached 16870660 kB
SwapCached 3212 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 2022-12-14 07:45:32
DTS_END                         2022-12-14 07:45:32.6850
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/60558
TRN_ID                          210689
ATT_ID                          622
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: 8783.70 21686 1054044
customer order: creation 160.81 480 19297
customer order: refuse 13.03 412 1563
order to supplier: creation 41.74 1332 5009
order to supplier: removal 3.31 192649 397
invoice (draft): creation 41.65 3359 4998
invoice (draft): removal 51.80 1043 6216
invoice accept: apply 38.57 26215 4628
invoice accept: cancel 46.73 1245 5608
customer reserve: creation 3576.68 249 429202
customer reserve: removal 4529.13 110 543495
realization accept: apply 78.66 289 9439
realization accept: cancel 80.94 163 9713
payment from customer: creation 55.15 170 6618
payment from customer: removal 5.01 126 601
payment to supplier: creation 46.18 213 5542
payment to supplier: removal 3.13 140 375
service: total inventory turnovers 3.75 4303 450
service: total monetary turnovers 6.64 395 797
service: aggregate perf. data 0.77 20536 92
service: refresh index statistics 0.03 180289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2022 07:46:14 to 14-12-2022 07:46:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8454
TEST_TIME 2 6207
TEST_TIME 3 6248
TEST_TIME 4 6051
TEST_TIME 5 5523
TEST_TIME 6 5243
TEST_TIME 7 5176
TEST_TIME 8 5041
TEST_TIME 9 5534
TEST_TIME 10 5432
TEST_TIME 11 5467
TEST_TIME 12 5686
TEST_TIME 13 5792
TEST_TIME 14 5984
TEST_TIME 15 6207
TEST_TIME 16 6369
TEST_TIME 17 6602
TEST_TIME 18 6960
TEST_TIME 19 6918
TEST_TIME 20 6964
TEST_TIME 21 7130
TEST_TIME 22 7273
TEST_TIME 23 7657
TEST_TIME 24 7815
TEST_TIME 25 7938
TEST_TIME 26 8206
TEST_TIME 27 8288
TEST_TIME 28 8469
TEST_TIME 29 8799
TEST_TIME 30 9351
TEST_TIME 31 9661
TEST_TIME 32 9685
TEST_TIME 33 10471
TEST_TIME 34 10570
TEST_TIME 35 10616
TEST_TIME 36 10333
TEST_TIME 37 10259
TEST_TIME 38 10397
TEST_TIME 39 10190
TEST_TIME 40 10027
TEST_TIME 41 9946
TEST_TIME 42 9815
TEST_TIME 43 9804
TEST_TIME 44 9657
TEST_TIME 45 9789
TEST_TIME 46 9697
TEST_TIME 47 9597
TEST_TIME 48 9513
TEST_TIME 49 9463
TEST_TIME 50 9501
TEST_TIME 51 9391
TEST_TIME 52 9268
TEST_TIME 53 9313
TEST_TIME 54 9228
TEST_TIME 55 9220
TEST_TIME 56 9177
TEST_TIME 57 9093
TEST_TIME 58 9093
TEST_TIME 59 9014
TEST_TIME 60 9028
TEST_TIME 61 9097
TEST_TIME 62 9024
TEST_TIME 63 9003
TEST_TIME 64 9037
TEST_TIME 65 9035
TEST_TIME 66 8963
TEST_TIME 67 8989
TEST_TIME 68 9016
TEST_TIME 69 9004
TEST_TIME 70 9001
TEST_TIME 71 9040
TEST_TIME 72 9032
TEST_TIME 73 9103
TEST_TIME 74 9150
TEST_TIME 75 9179
TEST_TIME 76 9157
TEST_TIME 77 9051
TEST_TIME 78 9041
TEST_TIME 79 9046
TEST_TIME 80 8999
TEST_TIME 81 8948
TEST_TIME 82 8912
TEST_TIME 83 8844
TEST_TIME 84 8874
TEST_TIME 85 8833
TEST_TIME 86 8791
TEST_TIME 87 8755
TEST_TIME 88 8730
TEST_TIME 89 8687
TEST_TIME 90 8727
TEST_TIME 91 8674
TEST_TIME 92 8659
TEST_TIME 93 8638
TEST_TIME 94 8658
TEST_TIME 95 8631
TEST_TIME 96 8610
TEST_TIME 97 8580
TEST_TIME 98 8615
TEST_TIME 99 8679
TEST_TIME 100 8694
TEST_TIME 101 8793
TEST_TIME 102 8855
TEST_TIME 103 8959
TEST_TIME 104 8995
TEST_TIME 105 8925
TEST_TIME 106 8887
TEST_TIME 107 8875
TEST_TIME 108 8863
TEST_TIME 109 8844
TEST_TIME 110 8815
TEST_TIME 111 8776
TEST_TIME 112 8749
TEST_TIME 113 8762
TEST_TIME 114 8791
TEST_TIME 115 8756
TEST_TIME 116 8782
TEST_TIME 117 8789
TEST_TIME 118 8805
TEST_TIME 119 8857
TEST_TIME 120 8809

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2022 07:46:17 to 14-12-2022 07:46:20.

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 1449934 1449934 0 0.00 0 32020 13 0 0
doc_list_biud 1449919 1449919 0 0.00 0 34484 47 0 0
sp_add_invoice_to_stock 4797 4628 169 3.52 33 204881 26216 0 169
sp_cancel_adding_invoice 5608 5608 0 0.00 5 87908 1245 0 0
sp_cancel_client_order 1563 1563 0 0.00 6 8015 413 0 0
sp_cancel_customer_reserve 543495 543495 0 0.00 1 21639 110 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 3 3952 127 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 9138 141 0 0
sp_cancel_supplier_invoice 6229 6216 13 0.20 2 25528 1043 0 13
sp_cancel_supplier_order 397 397 0 0.00 14 1404326 192650 0 0
sp_cancel_write_off 9713 9713 0 0.00 2 9547 163 0 0
sp_client_order 19297 19297 0 0.00 5 26579 481 0 0
sp_customer_reserve 429202 429202 0 0.00 0 47383 249 0 0
sp_fill_shopping_cart 29292 29292 0 0.00 1 16822 82 0 0
sp_fill_shopping_cart_clo_res 429174 429174 0 0.00 0 13062 21 0 0
sp_get_clo_for_invoice 4590 4590 0 0.00 0 26527 369 0 0
sp_kill_cost_storno 16297 16297 0 0.00 0 9072 73 0 0
sp_kill_qstorno_ret_qs2qd 1103316 1103316 0 0.00 0 25489 48 0 0
sp_kill_qty_storno 581015 581015 0 0.00 0 34480 110 0 0
sp_lock_selected_doc 19326 19326 0 0.00 0 5846 8 0 0
sp_make_cost_storno 26187 26187 0 0.00 0 22284 87 0 0
sp_multiply_rows_for_pdistr 26183 26183 0 0.00 0 8897 37 0 0
sp_multiply_rows_for_qdistr 19296 19296 0 0.00 0 11899 177 0 0
sp_pay_from_customer 6618 6618 0 0.00 5 10897 170 0 0
sp_pay_to_supplier 5542 5542 0 0.00 6 13096 214 0 0
sp_payment_common 12159 12159 0 0.00 2 11194 47 0 0
sp_qd_handle_on_cancel_clo 1563 1563 0 0.00 0 7999 335 0 0
sp_qd_handle_on_invoice_upd_sts 10193 10193 0 0.00 0 34467 581 0 0
sp_qd_handle_on_reserve_upd_sts 9438 9438 0 0.00 0 24229 66 0 0
sp_reserve_write_off 9439 9439 0 0.00 8 24244 289 0 0
sp_supplier_invoice 4998 4998 0 0.00 8 135143 3359 0 0
sp_supplier_order 5009 5009 0 0.00 8 33147 1332 0 0
srv_aggregate_perf_data 92 92 0 0.00 2814 188992 20537 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 775 155923 37229 0 0
srv_find_qd_qs_mism 1008913 1008913 0 0.00 0 32018 10 0 0
srv_make_invnt_saldo 450 450 0 0.00 58 84599 4303 0 0
srv_make_money_saldo 797 797 0 0.00 1 12833 395 0 0
srv_recalc_idx_stat 4 4 0 0.00 60464 286197 180290 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4634 4634 0 0.00 0 10699 906 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 7126 452 0 0
t$perf-norm:sp_cancel_client_order 1563 1563 0 0.00 0 2884 26 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 3940 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 3495 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 470 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 3558 40 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 92976 7945 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 6483 28 0 0
t$perf-norm:sp_client_order 19298 19298 0 0.00 0 7983 36 0 0
t$perf-norm:sp_customer_reserve 889 889 0 0.00 0 3600 16 0 0
t$perf-norm:sp_pay_from_customer 6619 6619 0 0.00 0 13600 21 0 0
t$perf-norm:sp_pay_to_supplier 5542 5542 0 0.00 0 9231 18 0 0
t$perf-norm:sp_reserve_write_off 9439 9439 0 0.00 0 4695 22 0 0
t$perf-norm:sp_supplier_invoice 4998 4998 0 0.00 0 5194 30 0 0
t$perf-norm:sp_supplier_order 5009 5009 0 0.00 0 7538 31 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 990 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 596 18 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 679 14 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 518 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 182 81 0 0
v_add_invoice_to_stock 9496 9158 338 3.55 0 5983 27 0 338
v_all_customers 35472 35472 0 0.00 0 71 4 0 0
v_all_suppliers 28111 28111 0 0.00 0 71 4 0 0
v_all_wares 38586 38586 0 0.00 0 95 4 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 1316 14 0 0
v_cancel_client_order 3126 3126 0 0.00 0 2552 10 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 2552 16 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 85 9 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 1015 12 0 0
v_cancel_supplier_invoice 804 778 26 3.23 0 2711 30 0 26
v_cancel_supplier_order 794 794 0 0.00 0 1013 14 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2618 25 0 0
v_max_id_clo_ord 5008 5008 0 0.00 0 296 5 0 0
v_max_id_clo_res 889 889 0 0.00 0 1162 10 0 0
v_max_id_ord_sup 4990 4990 0 0.00 0 4182 7 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 79 8 0 0
v_max_non_paid_realizn 3460 3460 0 0.00 0 5128 10 0 0
v_min_id_clo_ord 5008 5008 0 0.00 0 2475 10 0 0
v_min_id_clo_res 889 889 0 0.00 0 6972 20 0 0
v_min_id_ord_sup 4990 4990 0 0.00 0 5706 10 0 0
v_min_non_paid_invoice 5541 5541 0 0.00 0 11188 25 0 0
v_min_non_paid_realizn 6618 6618 0 0.00 0 4460 16 0 0
v_our_firm 9430 9430 0 0.00 0 65 4 0 0
v_reserve_write_off 18876 18876 0 0.00 0 3965 11 0 0
x_lock_depdocs_on_canc_invoice 11435 11435 0 0.00 0 19802 150 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7850 412 0 0
x_make_qstorno_1000_1200 5009 5009 0 0.00 3 33110 1160 0 0
x_make_qstorno_1200_2000 4998 4998 0 0.00 4 129992 3134 0 0
x_make_qstorno_2100_3300 429202 429202 0 0.00 0 40860 216 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-12-2022 07:46:21 to 14-12-2022 07:46:26.

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
05:15:36 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:16:38 30949 8192000000 9705490640 9998835712 782033232 8.714 100 90 180 0 812986144 8014128 22938784
05:18:14 20907 8192000000 9751051952 10056048640 826602368 9.165 100 89 178 0 860355120 12008608 21744144
05:19:45 9687 8192000000 9799993856 10117308416 866197296 9.562 100 78 156 0 908163136 23975536 17990304
05:20:58 8141 8192000000 9853845008 10187096064 916451088 10.061 100 71 140 0 960395584 29140288 14804208
05:22:10 3188 8192000000 9916412944 10259505152 958332256 10.473 100 86 164 2 1029582048 54529696 16720096
05:23:17 2464 8192000000 9990870784 10344685568 1002183360 10.900 100 92 182 0 1107694848 86962288 18549200
05:24:24 1175 8192000000 9985793408 10344464384 1022077408 11.092 100 90 167 1 1101214304 61608832 17528064
05:25:27 985 8192000000 10037624544 10409172992 1042548912 11.289 100 94 181 0 1156526448 95645952 18331584
05:26:29 994 8192000000 10025211040 10396069888 1055806816 11.416 100 97 188 0 1147183280 72708448 18668016
05:27:31 1054 8192000000 10034023536 10406641664 1066590016 11.520 100 95 185 0 1156843520 72159712 18093792
05:28:33 1041 8192000000 10043728528 10418937856 1074135920 11.592 100 95 186 0 1163744064 71048816 18559328
05:29:35 1366 8192000000 10039953392 10422243328 1074127040 11.591 100 95 179 2 1160553632 68314768 18111824
05:30:37 1215 8192000000 10044075392 10427613184 1077176320 11.621 100 93 174 2 1165348432 70055280 18116832
05:31:40 1141 8192000000 10027381072 10421395456 1078880944 11.637 100 89 158 2 1152876016 56448960 17546112
05:32:42 1023 8192000000 10027873328 10411298816 1083143904 11.677 100 92 179 4 1147439440 46185344 18110192
05:33:44 1972 8192000000 10041878368 10434916352 1085593376 11.701 100 67 121 2 1165859904 67517360 12749168
05:34:47 1003 8192000000 10037542944 10422177792 1092882896 11.770 100 95 180 1 1158211936 46505520 18823520
05:35:48 7802 8192000000 10041097328 10433773568 1087491056 11.719 100 58 116 1 1162391968 62942992 11957920
05:36:57 1471 8192000000 10026520880 10411364352 1076711744 11.616 100 87 162 4 1144562272 52015056 15835472
05:38:00 1863 8192000000 10043411824 10424872960 1080482640 11.652 100 86 165 2 1162460928 64949328 17028960
05:39:02 5421 8192000000 10028928432 10419494912 1054258976 11.402 97 60 114 1 1144136512 78373056 11504480
05:40:09 1832 8192000000 10019551280 10396348416 1047005824 11.332 99 80 150 0 1138170256 76524704 14639728
05:41:12 1091 8192000000 10027281584 10403094528 1050394192 11.364 100 95 183 0 1150926720 81480912 19051616
05:42:14 1011 8192000000 10016110736 10392137728 1053876048 11.398 100 96 184 1 1134325152 60024192 20424912
05:43:16 2132 8192000000 9965886224 10345881600 1025332768 11.123 99 71 130 4 1092848448 53121072 14394608
05:44:19 1095 8192000000 9984768496 10356826112 1033072464 11.198 99 92 181 0 1107668688 56341296 18254928
05:45:22 1614 8192000000 10003854480 10383101952 1054515024 11.404 100 77 141 2 1124753728 54384976 15853728
05:46:30 15956 8192000000 10025700064 10415874048 1070242576 11.554 100 55 107 0 1137523824 56981904 10299344
05:47:52 14119 8192000000 10052771888 10438795264 1083881840 11.684 100 72 142 0 1162016848 63657712 14477296
05:49:09 3722 8192000000 10095005216 10487304192 1101583952 11.853 100 79 150 1 1206047584 87633472 16830160
05:50:16 14499 8192000000 10112389728 10507046912 1109953312 11.932 100 63 124 0 1229974480 107196832 12824336
05:51:37 10910 8192000000 10171548352 10573447168 1127026688 12.093 100 71 136 1 1282355312 140278272 15050352
05:53:05 7338 8192000000 10247545648 10650357760 1141821184 12.233 100 63 125 0 1352886624 196962528 14102912
05:54:21 5101 8192000000 10235985488 10665132032 1155037504 12.357 100 68 124 0 1350899920 180929680 14932736
05:55:30 5242 8192000000 10278637472 10683699200 1161540352 12.418 100 67 132 0 1394462128 217558704 15363072
05:56:41 1809 8192000000 10332074560 10737815552 1170126448 12.498 100 93 183 0 1437506000 246572208 20807344
05:57:47 1109 8192000000 10367326464 10775478272 1185105360 12.638 100 90 173 0 1479762592 274044864 20612368
05:58:50 1876 8192000000 10393816928 10797543424 1184469200 12.632 100 96 190 1 1510679760 303583792 22626768
05:59:54 945 8192000000 10422062560 10842206208 1188503568 12.669 100 98 192 2 1533882960 321955520 23423872
06:00:59 926 8192000000 10447060240 10857500672 1183038768 12.619 100 97 192 1 1560468048 354852048 22577232
06:02:05 760 8192000000 10467431536 10893287424 1188117376 12.666 100 93 181 2 1569590928 361199984 20273568
06:03:08 842 8192000000 10458954096 10900164608 1184954640 12.636 100 97 185 1 1568880192 362458160 21467392
06:04:12 1984 8192000000 10487159056 10898108416 1180943408 12.599 100 89 163 1 1589731680 390324032 18464240
06:05:19 1422 8192000000 10472687184 10913525760 1167482848 12.473 99 86 166 1 1576889376 390212672 19193856
06:06:23 1866 8192000000 10520835328 10968735744 1188232704 12.667 100 94 181 1 1633097488 422125616 22739168
06:07:29 902 8192000000 10504351456 10950258688 1175407488 12.547 99 99 197 0 1608028736 409255984 23365264
06:08:34 1414 8192000000 10518776640 10995232768 1198518960 12.763 100 94 180 1 1623404704 401352640 23533104
06:09:38 878 8192000000 10503301760 10942738432 1188559824 12.670 99 93 180 1 1609187728 398561472 22066432
06:10:40 3302 8192000000 10531125408 10958340096 1185648800 12.643 100 65 127 0 1632081040 430116000 16316240
06:11:46 3579 8192000000 10527371088 10988802048 1190821392 12.691 100 75 139 1 1630661344 423492352 16347600
06:12:50 8011 8192000000 10527735088 10989322240 1185127888 12.638 100 49 96 0 1636603952 439001424 12474640
06:14:03 4440 8192000000 10490186176 10978897920 1174255328 12.537 100 51 102 1 1606718960 419125360 13338272
06:15:08 16020 8192000000 10399679008 10870272000 1155895872 12.365 99 32 64 0 1512119680 347661552 8562256
06:16:32 15334 8192000000 10329834912 10762805248 1162286480 12.425 100 40 80 0 1414108416 243213696 8608240
06:17:49 15884 8192000000 10289058592 10790461440 1142621200 12.240 99 34 65 1 1401133552 250932960 7579392
06:19:06 50757 8192000000 10138782752 10574704640 1121564688 12.042 98 19 36 0 1216517856 90647088 4306080
06:20:57 75921 8192000000 10120307024 10547621888 1112044496 11.952 100 49 89 2 1212389616 89771504 10573616
06:23:18 23621 8192000000 10055763456 10527657984 1117877424 12.007 100 15 24 1 1149018848 28739936 2401488
06:24:48 129699 8192000000 10130950672 10605301760 1138256896 12.199 100 21 43 0 1225342576 82531920 4553760
06:28:04 80472 8192000000 10124389616 10574643200 1091254320 11.755 96 13 26 0 1183711744 89678960 2778464
06:30:27 76922 8192000000 10085432640 10547011584 1104071456 11.876 98 13 24 0 1170699456 64159488 2468512
06:33:01 155923 8192000000 10055512336 10570711040 1097587952 11.815 97 25 50 1 1177308128 73197232 6522944
06:36:41 9587 8192000000 10070105072 10506600448 1123292496 12.058 100 28 52 1 1171044368 42627024 5124848
06:37:53 13012 8192000000 10113124928 10544275456 1126319824 12.087 100 62 122 0 1204082336 64747792 13014720
06:39:11 43789 8192000000 10097597424 10548318208 1123304288 12.058 99 35 63 0 1219634976 88581424 7749264
06:40:59 58635 8192000000 10112265248 10578096128 1139309840 12.209 100 15 30 0 1207763072 65029552 3423680
06:42:58 46327 8192000000 10128889568 10599608320 1141877456 12.233 100 9 19 0 1241747440 97252720 2617264
06:44:47 77216 8192000000 10089088416 10583744512 1130060672 12.122 99 24 46 0 1196811472 61645536 5105264
06:47:16 51237 8192000000 10070154704 10521010176 1102057088 11.857 97 23 45 0 1139716736 33390000 4269648
06:49:08 61344 8192000000 10047839520 10485149696 1097434496 11.813 97 18 35 0 1128677872 27514256 3729120
06:51:09 26619 8192000000 10071747520 10498187264 1118177328 12.010 99 48 93 0 1170183680 42776208 9230144
06:52:37 54341 8192000000 10081454624 10519924736 1133050576 12.150 100 17 35 0 1202883200 66300800 3531824
06:54:32 43101 8192000000 10113211760 10559827968 1136483392 12.182 100 24 49 0 1191599440 49839840 5276208
06:56:15 65520 8192000000 10118934592 10564780032 1113075680 11.962 97 22 45 0 1198424352 80641408 4707264
06:58:21 39839 8192000000 10105593744 10626170880 1120743136 12.034 99 23 45 0 1181646064 56398160 4504768
07:00:04 119987 8192000000 10101344992 10577719296 1079028800 11.638 95 24 48 0 1131540464 47741376 4770288
07:03:06 82554 8192000000 10101101232 10581827584 1124771200 12.072 99 31 62 0 1238221584 106582832 6867552
07:05:38 48979 8192000000 10039277472 10490822656 1102119408 11.858 97 18 37 0 1122762192 16324768 4318016
07:07:30 72474 8192000000 10070016896 10532970496 1106430800 11.899 98 9 19 0 1165448944 57181568 1836576
07:10:15 86960 8192000000 10033002304 10472538112 1076424176 11.613 95 14 27 0 1118227312 38973280 2829856
07:12:42 25330 8192000000 10073695840 10515447808 1112600208 11.957 99 33 65 0 1176578592 57419648 6558736
07:14:08 71448 8192000000 10090746416 10558480384 1099423408 11.832 98 21 42 1 1174068112 70162528 4482176
07:16:35 28057 8192000000 10049966528 10485366784 1072078272 11.572 95 41 75 5 1109382880 28040512 9264096
07:18:03 19487 8192000000 10098139104 10531971072 1113555632 11.966 99 39 76 0 1194344704 72520464 8268608
07:19:28 34804 8192000000 10133411168 10581360640 1105603808 11.891 97 30 60 0 1219900224 107915680 6380736
07:21:03 6319 8192000000 10200906160 10626723840 1135387312 12.172 99 48 95 1 1290277056 143551088 11338656
07:22:12 7956 8192000000 10265142192 10690174976 1150854960 12.318 100 56 110 0 1347659344 183917456 12886928
07:23:25 12057 8192000000 10295561024 10745630720 1148847488 12.299 100 49 98 0 1399772768 239290816 11634464
07:24:38 45352 8192000000 10268007792 10722529280 1149196144 12.302 100 38 76 0 1366795024 208517088 9081792
07:26:23 31200 8192000000 10219074880 10739036160 1137260688 12.190 100 28 56 0 1352531136 208518752 6751696
07:28:04 57880 8192000000 10052263392 10522505216 1094478720 11.785 97 23 44 0 1201156320 101460576 5217024
07:30:02 78012 8192000000 10093417344 10539683840 1125598736 12.080 100 57 111 3 1179053936 42436112 11019088
07:32:20 88286 8192000000 10094927600 10556207104 1120183024 12.029 99 11 22 0 1183694608 61312000 2199584
07:35:20 67526 8192000000 10058846496 10517471232 1083975232 11.685 96 13 26 0 1111298912 24101584 3222096
07:37:30 61021 8192000000 10109634832 10574458880 1093461504 11.776 97 23 46 0 1145673328 47417552 4794272
07:39:51 75346 8192000000 10082158976 10546307072 1105290592 11.888 99 13 23 0 1188676448 81028800 2357056
07:42:19 140606 8192000000 10076748528 10569191424 1088537696 11.729 97 16 32 0 1162062512 70529056 2995760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-12-2022 07:46:28 to 14-12-2022 07:46:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2022 07:46:45 to 14-12-2022 07:46:47.

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          209768
MON$OLDEST_ACTIVE               210854
MON$OLDEST_SNAPSHOT             210854
MON$NEXT_TRANSACTION            210854
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                       2061984
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.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Dec 14 07:45:37 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Dec 14 07:45:43 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Dec 14 07:45:44 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

14.12.22 07:46:48. 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
2022-12-14 07:46:50 No severe PSQL-related problems occured
14.12.22 07:46:52. End of report.