Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2022 04:37
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 14442684 kB
MemAvailable 30047068 kB
Buffers 20 kB
Cached 17162292 kB
SwapCached 3268 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-09 04:37:23
DTS_END                         2022-12-09 04:37:23.7040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38992
TRN_ID                          220984
ATT_ID                          670
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: 9278.12 24088 1113374
customer order: creation 172.54 440 20705
customer order: refuse 14.58 395 1749
order to supplier: creation 45.86 1263 5503
order to supplier: removal 3.49 166883 419
invoice (draft): creation 45.83 3156 5500
invoice (draft): removal 53.94 920 6473
invoice accept: apply 40.95 22362 4914
invoice accept: cancel 48.12 1002 5774
customer reserve: creation 3834.93 209 460191
customer reserve: removal 4724.48 97 566937
realization accept: apply 81.13 269 9736
realization accept: cancel 85.29 150 10235
payment from customer: creation 57.84 179 6941
payment from customer: removal 5.53 118 664
payment to supplier: creation 48.25 203 5790
payment to supplier: removal 3.36 117 403
service: total inventory turnovers 3.71 4130 445
service: total monetary turnovers 7.36 307 883
service: aggregate perf. data 0.89 14596 107
service: refresh index statistics 0.04 264966 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2022 04:38:07 to 09-12-2022 04:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4622
TEST_TIME 2 3906
TEST_TIME 3 5229
TEST_TIME 4 5868
TEST_TIME 5 5703
TEST_TIME 6 5937
TEST_TIME 7 5790
TEST_TIME 8 5969
TEST_TIME 9 6038
TEST_TIME 10 6019
TEST_TIME 11 6166
TEST_TIME 12 6308
TEST_TIME 13 6568
TEST_TIME 14 6676
TEST_TIME 15 6832
TEST_TIME 16 6936
TEST_TIME 17 7290
TEST_TIME 18 7478
TEST_TIME 19 7619
TEST_TIME 20 7674
TEST_TIME 21 7786
TEST_TIME 22 7828
TEST_TIME 23 8037
TEST_TIME 24 8469
TEST_TIME 25 8782
TEST_TIME 26 9067
TEST_TIME 27 9280
TEST_TIME 28 10013
TEST_TIME 29 10141
TEST_TIME 30 10615
TEST_TIME 31 10697
TEST_TIME 32 10481
TEST_TIME 33 10664
TEST_TIME 34 11053
TEST_TIME 35 10777
TEST_TIME 36 10765
TEST_TIME 37 10727
TEST_TIME 38 10607
TEST_TIME 39 10467
TEST_TIME 40 10379
TEST_TIME 41 10513
TEST_TIME 42 10395
TEST_TIME 43 10413
TEST_TIME 44 10308
TEST_TIME 45 10283
TEST_TIME 46 10236
TEST_TIME 47 10265
TEST_TIME 48 10232
TEST_TIME 49 10050
TEST_TIME 50 9953
TEST_TIME 51 9768
TEST_TIME 52 9713
TEST_TIME 53 9703
TEST_TIME 54 9595
TEST_TIME 55 9606
TEST_TIME 56 9551
TEST_TIME 57 9495
TEST_TIME 58 9558
TEST_TIME 59 9477
TEST_TIME 60 9432
TEST_TIME 61 9412
TEST_TIME 62 9363
TEST_TIME 63 9321
TEST_TIME 64 9405
TEST_TIME 65 9332
TEST_TIME 66 9392
TEST_TIME 67 9430
TEST_TIME 68 9468
TEST_TIME 69 9607
TEST_TIME 70 9527
TEST_TIME 71 9592
TEST_TIME 72 9665
TEST_TIME 73 9897
TEST_TIME 74 9880
TEST_TIME 75 9869
TEST_TIME 76 9919
TEST_TIME 77 9813
TEST_TIME 78 9763
TEST_TIME 79 9810
TEST_TIME 80 9733
TEST_TIME 81 9681
TEST_TIME 82 9662
TEST_TIME 83 9651
TEST_TIME 84 9598
TEST_TIME 85 9579
TEST_TIME 86 9516
TEST_TIME 87 9513
TEST_TIME 88 9504
TEST_TIME 89 9439
TEST_TIME 90 9371
TEST_TIME 91 9348
TEST_TIME 92 9351
TEST_TIME 93 9319
TEST_TIME 94 9292
TEST_TIME 95 9309
TEST_TIME 96 9302
TEST_TIME 97 9290
TEST_TIME 98 9278
TEST_TIME 99 9236
TEST_TIME 100 9321
TEST_TIME 101 9344
TEST_TIME 102 9361
TEST_TIME 103 9357
TEST_TIME 104 9431
TEST_TIME 105 9507
TEST_TIME 106 9534
TEST_TIME 107 9486
TEST_TIME 108 9426
TEST_TIME 109 9475
TEST_TIME 110 9448
TEST_TIME 111 9411
TEST_TIME 112 9406
TEST_TIME 113 9356
TEST_TIME 114 9336
TEST_TIME 115 9355
TEST_TIME 116 9326
TEST_TIME 117 9308
TEST_TIME 118 9313
TEST_TIME 119 9339
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-12-2022 04:38:10 to 09-12-2022 04:38:14.

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 1537562 1537562 0 0.00 0 17889 11 0 0
doc_list_biud 1537562 1537562 0 0.00 0 53247 41 0 0
sp_add_invoice_to_stock 5055 4914 141 2.78 60 209268 22363 0 141
sp_cancel_adding_invoice 5774 5774 0 0.00 7 96083 1002 0 0
sp_cancel_client_order 1749 1749 0 0.00 5 9464 395 0 0
sp_cancel_customer_reserve 566937 566937 0 0.00 1 48391 98 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 6093 118 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 7724 117 0 0
sp_cancel_supplier_invoice 6486 6473 13 0.20 2 53401 921 0 13
sp_cancel_supplier_order 419 419 0 0.00 10 1326703 166884 0 0
sp_cancel_write_off 10235 10235 0 0.00 1 12864 150 0 0
sp_client_order 20705 20705 0 0.00 5 16739 441 0 0
sp_customer_reserve 460191 460191 0 0.00 1 107649 210 0 0
sp_fill_shopping_cart 31704 31704 0 0.00 1 16788 82 0 0
sp_fill_shopping_cart_clo_res 460183 460183 0 0.00 0 16854 19 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 24012 317 0 0
sp_kill_cost_storno 17076 17076 0 0.00 0 12701 67 0 0
sp_kill_qstorno_ret_qs2qd 1151001 1151001 0 0.00 0 53246 43 0 0
sp_kill_qty_storno 606216 606216 0 0.00 0 53247 97 0 0
sp_lock_selected_doc 20311 20311 0 0.00 0 11467 9 0 0
sp_make_cost_storno 27360 27360 0 0.00 0 13485 83 0 0
sp_multiply_rows_for_pdistr 27360 27360 0 0.00 0 9870 39 0 0
sp_multiply_rows_for_qdistr 20705 20705 0 0.00 0 10868 189 0 0
sp_pay_from_customer 6941 6941 0 0.00 5 28029 180 0 0
sp_pay_to_supplier 5790 5790 0 0.00 7 15130 203 0 0
sp_payment_common 12731 12731 0 0.00 2 28021 53 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 8070 297 0 0
sp_qd_handle_on_invoice_upd_sts 10667 10667 0 0.00 0 38496 467 0 0
sp_qd_handle_on_reserve_upd_sts 9736 9736 0 0.00 0 9934 54 0 0
sp_reserve_write_off 9736 9736 0 0.00 8 13076 270 0 0
sp_supplier_invoice 5500 5500 0 0.00 10 148250 3156 0 0
sp_supplier_order 5503 5503 0 0.00 7 32707 1264 0 0
srv_aggregate_perf_data 107 107 0 0.00 2124 107714 14596 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 1021 230761 35026 0 0
srv_find_qd_qs_mism 1064996 1064996 0 0.00 0 9710 8 0 0
srv_make_invnt_saldo 445 445 0 0.00 41 91993 4130 0 0
srv_make_money_saldo 883 883 0 0.00 1 13618 307 0 0
srv_recalc_idx_stat 5 5 0 0.00 98574 396981 264966 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 4914 4914 0 0.00 1 18356 799 0 0
t$perf-norm:sp_cancel_adding_invoice 366 366 0 0.00 1 5259 385 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 4795 30 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 566 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 8748 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2828 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 1399 29 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 73658 5638 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 2084 16 0 0
t$perf-norm:sp_client_order 20705 20705 0 0.00 0 22588 35 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1995 16 0 0
t$perf-norm:sp_pay_from_customer 6941 6941 0 0.00 0 6509 18 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 4614 21 0 0
t$perf-norm:sp_reserve_write_off 9736 9736 0 0.00 0 8889 23 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 6102 28 0 0
t$perf-norm:sp_supplier_order 5503 5503 0 0.00 0 9369 30 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 346 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1112 24 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 454 12 0 0
t$perf-norm:srv_make_money_saldo 883 883 0 0.00 0 1545 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 167 62 0 0
v_add_invoice_to_stock 10068 9786 282 2.80 0 5720 23 0 282
v_all_customers 37894 37894 0 0.00 0 66 3 0 0
v_all_suppliers 30790 30790 0 0.00 0 69 4 0 0
v_all_wares 41410 41410 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 732 732 0 0.00 0 1312 15 0 0
v_cancel_client_order 3498 3498 0 0.00 0 7203 12 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 3961 12 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 1767 12 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1765 11 0 0
v_cancel_supplier_invoice 900 874 26 2.88 0 4968 30 0 26
v_cancel_supplier_order 838 838 0 0.00 0 7195 18 0 0
v_cancel_write_off 1984 1984 0 0.00 0 5695 27 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 549 4 0 0
v_max_id_clo_res 993 993 0 0.00 0 2788 19 0 0
v_max_id_ord_sup 5496 5496 0 0.00 0 5906 10 0 0
v_max_non_paid_invoice 1394 1394 0 0.00 0 509 7 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 7718 11 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 2925 8 0 0
v_min_id_clo_res 993 993 0 0.00 0 3031 18 0 0
v_min_id_ord_sup 5496 5496 0 0.00 0 15205 18 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 15117 28 0 0
v_min_non_paid_realizn 6941 6941 0 0.00 0 28016 26 0 0
v_our_firm 10400 10400 0 0.00 0 63 4 0 0
v_reserve_write_off 19472 19472 0 0.00 0 5660 10 0 0
x_lock_depdocs_on_canc_invoice 11810 11810 0 0.00 0 11195 122 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 11420 348 0 0
x_make_qstorno_1000_1200 5503 5503 0 0.00 2 31174 1111 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 4 148041 2915 0 0
x_make_qstorno_2100_3300 460191 460191 0 0.00 0 107647 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2022 04:38:15 to 09-12-2022 04:38:19.

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:27 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 21363 8192000000 9713552416 10008739840 788826272 8.783 100 85 168 0 816960688 7329248 20805168
02:09:55 19533 8192000000 9750239424 10055303168 825093376 9.150 100 86 172 0 857378928 11350384 20935168
02:11:22 11017 8192000000 9788587392 10107576320 860699056 9.507 100 78 156 0 900511552 21544032 18268464
02:12:35 7761 8192000000 9858832432 10194620416 917487584 10.071 100 78 155 0 973284944 38237776 17559584
02:13:46 4625 8192000000 9907764224 10252488704 964073696 10.529 100 84 164 0 1025346368 44517456 16755216
02:14:53 1440 8192000000 9979006624 10330488832 998692672 10.866 100 94 185 0 1094565664 77007600 18865392
02:15:57 1350 8192000000 10007782928 10369077248 1018230192 11.055 100 94 184 0 1124627280 88147984 18249104
02:16:59 4806 8192000000 10013725040 10380017664 1036233152 11.228 100 84 166 1 1129531024 78028176 15269696
02:18:06 954 8192000000 10027765808 10406359040 1059052848 11.447 100 92 180 2 1150596208 74467712 17075648
02:19:08 3411 8192000000 10021186736 10398556160 1065371472 11.508 100 81 153 2 1148963360 68529648 15062240
02:20:12 1156 8192000000 10026910688 10413182976 1071516816 11.567 100 80 154 1 1144491664 57753344 15221504
02:21:14 2695 8192000000 10029329568 10411425792 1077364768 11.622 100 73 138 0 1148101888 57175040 13562080
02:22:18 1112 8192000000 10051608288 10439024640 1085702928 11.702 100 96 182 0 1175999200 72466896 17829376
02:23:20 1118 8192000000 10049389696 10436395008 1085846224 11.703 100 92 180 0 1170243424 65692928 18704272
02:24:22 1023 8192000000 10057329392 10445922304 1095318208 11.793 100 94 185 2 1178552304 64054800 19179296
02:25:25 1177 8192000000 10062707600 10450616320 1093487520 11.776 100 98 189 1 1183541024 70769952 19283552
02:26:27 7604 8192000000 10037913984 10434224128 1093667520 11.778 100 63 121 0 1173860288 67795312 12397456
02:27:36 5601 8192000000 10037287904 10433015808 1096511552 11.805 100 68 134 1 1162161248 51686016 13963680
02:28:43 2155 8192000000 10076447616 10472792064 1101227776 11.849 100 69 131 1 1189525536 74973376 13324384
02:29:46 1049 8192000000 10031323072 10415214592 1069216480 11.545 100 93 179 2 1155745568 66729744 19799344
02:30:48 1058 8192000000 10043180080 10425458688 1065674384 11.511 100 97 187 0 1164817856 80789616 18353856
02:31:50 5685 8192000000 10034275680 10429132800 1064977424 11.504 100 74 146 0 1149537728 69768480 14791824
02:32:58 4541 8192000000 10007128464 10391011328 1058386752 11.441 100 65 122 4 1117974544 47611776 11976016
02:34:03 4562 8192000000 9986749216 10357604352 1013373792 11.008 97 74 139 1 1090465280 61963056 15128432
02:35:08 4896 8192000000 9983973920 10374033408 1042367600 11.287 100 57 110 0 1110076656 56030880 11678176
02:36:14 824 8192000000 10012592544 10387304448 1048709424 11.348 100 88 163 0 1127431872 62136304 16586144
02:37:16 9867 8192000000 9968444400 10362634240 1044566896 11.309 99 49 95 0 1099282672 45220736 9495040
02:38:38 1360 8192000000 9987235632 10362880000 1066074464 11.515 100 85 151 3 1102869392 21418784 15376144
02:39:46 1226 8192000000 10012945952 10398281728 1065993520 11.514 100 60 116 1 1135851968 58393248 11465200
02:40:54 1016 8192000000 10073307616 10465599488 1091274704 11.755 100 89 172 0 1189890480 81725264 16890512
02:41:59 4898 8192000000 10084197456 10477309952 1102061136 11.857 100 69 128 0 1202447968 87500288 12886544
02:43:09 7383 8192000000 10100636960 10507575296 1118750928 12.015 100 58 108 1 1222955712 92294784 11910000
02:44:19 6581 8192000000 10142087456 10552688640 1130188528 12.123 100 69 134 0 1262981456 118556432 14236496
02:45:30 1119 8192000000 10213273120 10607644672 1142946960 12.243 100 92 172 1 1333436544 169327040 21162544
02:46:36 1728 8192000000 10241321200 10632314880 1153948320 12.347 100 86 160 1 1346300192 175718928 16632944
02:47:45 3566 8192000000 10270732144 10675662848 1167558096 12.474 100 72 143 0 1381537488 197197360 16782032
02:48:54 2720 8192000000 10305318224 10709454848 1183821520 12.626 100 78 154 2 1420185072 217900176 18463376
02:50:03 1577 8192000000 10324098640 10745520128 1191277136 12.695 100 86 162 1 1444084176 233582704 19224336
02:51:07 1031 8192000000 10388983888 10789040128 1189865488 12.682 100 94 184 1 1495651312 282787328 22998496
02:52:12 1584 8192000000 10410831232 10831237120 1190385232 12.687 100 87 174 0 1520058176 309509120 20163824
02:53:16 1423 8192000000 10437557696 10857136128 1192886288 12.710 100 94 178 2 1543047104 329029872 21130944
02:54:22 1393 8192000000 10435918560 10860847104 1189232432 12.676 100 97 182 0 1542007440 330750784 22024224
02:55:25 3922 8192000000 10434449152 10864824320 1192597696 12.708 100 58 116 0 1541414896 334498528 14318672
02:56:32 1858 8192000000 10495938544 10912878592 1194915120 12.729 100 73 146 0 1604667216 391933536 17818560
02:57:45 2372 8192000000 10513260048 10938290176 1191777248 12.700 100 77 146 0 1621114240 411925008 17411984
02:58:50 10076 8192000000 10529808976 10966048768 1198734224 12.765 100 54 106 1 1632992816 420734592 13524000
03:00:07 5559 8192000000 10530145440 10995474432 1177419952 12.566 100 52 103 1 1653075808 461578880 14076976
03:01:15 19850 8192000000 10509245296 10972250112 1165215776 12.452 100 41 82 0 1620783888 444551648 11016464
03:02:40 10316 8192000000 10466768976 10927382528 1159485856 12.398 99 40 78 0 1568518752 398753984 10278912
03:03:56 24790 8192000000 10405115136 10907336704 1161159360 12.414 100 35 71 1 1551367840 380779152 9429328
03:05:26 21106 8192000000 10257667808 10770903040 1150444336 12.314 100 23 44 0 1412635264 256457488 5733440
03:06:53 42520 8192000000 10189781296 10623549440 1108794976 11.921 97 26 49 0 1300000032 185338848 5866208
03:08:37 13187 8192000000 10191946160 10640945152 1138016464 12.197 99 43 76 1 1293922512 147602336 8303712
03:09:58 33576 8192000000 10185796880 10655006720 1130028400 12.122 99 16 32 0 1289360768 155618176 3714192
03:12:20 38737 8192000000 10084506896 10503090176 1118780592 12.015 99 43 86 0 1149003120 21316544 8905984
03:14:01 111032 8192000000 10061540800 10497880064 1053706592 11.396 93 17 33 0 1088635504 32259904 2669008
03:17:02 125197 8192000000 10072783056 10569932800 1102244512 11.859 97 10 19 0 1176553424 72605088 1703824
03:20:08 55918 8192000000 10069522560 10511532032 1120455104 12.031 99 23 44 1 1168550416 43850752 4244560
03:22:11 17796 8192000000 10073878096 10495197184 1112018208 11.952 98 22 42 1 1155020176 38309600 4692368
03:23:31 8679 8192000000 10081175248 10490376192 1129834624 12.120 100 62 106 6 1171580416 30368960 11376832
03:24:46 101483 8192000000 10064890256 10502152192 1115512240 11.985 98 16 32 1 1180840112 61797520 3530352
03:27:30 230759 8192000000 10060278576 10527694848 1095040112 11.791 97 23 46 0 1162471472 62406320 5025040
03:32:22 18347 8192000000 10107662096 10542731264 1127439248 12.097 99 32 64 0 1214081696 79528496 7113952
03:33:42 12811 8192000000 10141217168 10573533184 1141496256 12.230 99 27 53 0 1227290624 79132496 6661872
03:34:55 62609 8192000000 10174888384 10673340416 1132335024 12.143 99 28 54 2 1271013920 132666128 6012768
03:36:58 38589 8192000000 10173133280 10643685376 1141474992 12.229 100 21 40 0 1269761664 123919152 4367520
03:39:39 34112 8192000000 10237808816 10708942848 1157057040 12.376 100 19 38 1 1322849776 160755776 5036960
03:41:42 82514 8192000000 10266557696 10762911744 1172927952 12.524 100 21 38 1 1344060016 166464496 4667568
03:44:25 55367 8192000000 10281815008 10752339968 1170346688 12.500 99 24 48 0 1364584848 188521024 5717136
03:46:27 35832 8192000000 10224972432 10702872576 1115514080 11.985 94 27 53 0 1301060352 179269904 6276368
03:48:04 7193 8192000000 10291716288 10714009600 1154896496 12.355 99 68 133 0 1372157120 201790384 15470240
03:49:26 29202 8192000000 10216438432 10678611968 1133333296 12.153 98 26 50 0 1290869888 151938928 5597664
03:50:55 37196 8192000000 10063961184 10494148608 1102283888 11.859 97 28 56 0 1141164624 33105632 5775104
03:52:35 76661 8192000000 10121774080 10616299520 1122503344 12.051 98 22 44 0 1195087888 68417856 4166688
03:55:07 75574 8192000000 10110689728 10598555648 1123701312 12.062 99 25 50 1 1219772368 90009680 6061376
03:57:31 24567 8192000000 10091148416 10554454016 1130997552 12.131 100 33 66 0 1181063920 43706976 6359392
03:59:01 16911 8192000000 10089771392 10539966464 1136222000 12.180 100 37 74 0 1182771328 39391424 7157904
04:00:35 47167 8192000000 10083734512 10562912256 1132487504 12.145 100 16 32 0 1183980784 48245712 3247568
04:02:23 39126 8192000000 10091035472 10571218944 1098404560 11.823 98 9 18 0 1183998432 83255376 2338496
04:04:52 43156 8192000000 10027947600 10520920064 1092686336 11.768 98 9 18 1 1134028832 39128016 2214480
04:06:59 97791 8192000000 10044324768 10508283904 1072434512 11.575 96 19 39 0 1137805904 61154000 4217392
04:09:49 75296 8192000000 10041456304 10491658240 1067140240 11.525 97 23 40 0 1109621152 37988512 4492400
04:12:14 55325 8192000000 10044602464 10510385152 1058377264 11.441 95 24 48 0 1145386224 81597040 5411920
04:14:10 33796 8192000000 10139983696 10567999488 1129412304 12.116 100 33 62 0 1225726224 89087776 7226144
04:15:44 13969 8192000000 10162846544 10597253120 1111549632 11.947 98 27 54 0 1248578096 131142784 5885680
04:17:03 23603 8192000000 10215198864 10674221056 1136900096 12.186 100 41 82 1 1313207728 167019680 9287952
04:18:40 22251 8192000000 10196068960 10703187968 1139156176 12.208 100 40 80 0 1320772448 172568592 9047680
04:20:09 27030 8192000000 10202572992 10637209600 1140852992 12.224 99 38 76 0 1293718560 144723984 8141584
04:21:54 67826 8192000000 10108956528 10625871872 1147148896 12.283 100 26 52 0 1256196608 103586176 5461536
04:24:05 46933 8192000000 10142689520 10593525760 1129744864 12.119 98 43 85 0 1207269520 68639040 8885616
04:26:19 69015 8192000000 10090580576 10576310272 1139570032 12.211 100 15 30 0 1170616272 27730752 3315488
04:28:28 79338 8192000000 10069919456 10554449920 1136195904 12.180 99 10 20 0 1177292816 39434096 1662816
04:31:05 63596 8192000000 10092149088 10532765696 1111219584 11.944 97 20 40 0 1163217456 47814688 4183184
04:33:16 29967 8192000000 10097806048 10539929600 1144011344 12.253 100 22 44 0 1184929312 36415520 4502448
04:34:58 51135 8192000000 10111258320 10584608768 1115084208 11.981 98 27 54 0 1181878112 61189024 5604880
04:37:01 30518 8192000000 9088370160 9330630656 242190016 2.871 22 4 4 1 251116096 8681008 245072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-12-2022 04:38:21 to 09-12-2022 04:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 09-12-2022 04:38:38 to 09-12-2022 04:38:40.

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          221305
MON$OLDEST_ACTIVE               221306
MON$OLDEST_SNAPSHOT             221306
MON$NEXT_TRANSACTION            221306
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                       2093888
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2882 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2882 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2882 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2882 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 ---
--- end of diff output ---

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

09.12.22 04:38:41. 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-09 04:38:44 No severe PSQL-related problems occured
09.12.22 04:38:45. End of report.