Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2023 10:38
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 15001288 kB
MemAvailable 30435244 kB
Buffers 0 kB
Cached 16725604 kB
SwapCached 3388 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-14 10:37:25
DTS_END                         2023-02-14 10:37:25.3800
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/50874
TRN_ID                          220554
ATT_ID                          647
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: 9094.17 22728 1091300
customer order: creation 169.90 456 20388
customer order: refuse 14.61 405 1753
order to supplier: creation 45.08 1391 5410
order to supplier: removal 3.49 162331 419
invoice (draft): creation 44.41 3249 5329
invoice (draft): removal 51.61 1008 6193
invoice accept: apply 40.50 25357 4860
invoice accept: cancel 46.46 1170 5575
customer reserve: creation 3828.72 238 459446
customer reserve: removal 4562.24 98 547469
realization accept: apply 80.91 275 9709
realization accept: cancel 80.74 169 9689
payment from customer: creation 57.28 161 6873
payment from customer: removal 5.23 108 628
payment to supplier: creation 47.08 208 5650
payment to supplier: removal 3.58 121 429
service: total inventory turnovers 4.04 3871 485
service: total monetary turnovers 7.27 413 872
service: aggregate perf. data 0.98 14034 118
service: refresh index statistics 0.04 239501 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2023 10:39:18 to 14-02-2023 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4554
TEST_TIME 2 4257
TEST_TIME 3 5295
TEST_TIME 4 5441
TEST_TIME 5 6022
TEST_TIME 6 6073
TEST_TIME 7 6078
TEST_TIME 8 5862
TEST_TIME 9 6096
TEST_TIME 10 6046
TEST_TIME 11 6056
TEST_TIME 12 6207
TEST_TIME 13 6301
TEST_TIME 14 6300
TEST_TIME 15 6401
TEST_TIME 16 6541
TEST_TIME 17 6598
TEST_TIME 18 7061
TEST_TIME 19 7259
TEST_TIME 20 7428
TEST_TIME 21 7438
TEST_TIME 22 7824
TEST_TIME 23 8119
TEST_TIME 24 8254
TEST_TIME 25 8173
TEST_TIME 26 8323
TEST_TIME 27 9084
TEST_TIME 28 9284
TEST_TIME 29 9672
TEST_TIME 30 10114
TEST_TIME 31 10603
TEST_TIME 32 10916
TEST_TIME 33 10887
TEST_TIME 34 10905
TEST_TIME 35 10996
TEST_TIME 36 10996
TEST_TIME 37 10832
TEST_TIME 38 10782
TEST_TIME 39 10585
TEST_TIME 40 10546
TEST_TIME 41 10506
TEST_TIME 42 10426
TEST_TIME 43 10385
TEST_TIME 44 10219
TEST_TIME 45 10244
TEST_TIME 46 10119
TEST_TIME 47 10076
TEST_TIME 48 10188
TEST_TIME 49 10097
TEST_TIME 50 10080
TEST_TIME 51 10062
TEST_TIME 52 9934
TEST_TIME 53 9867
TEST_TIME 54 9887
TEST_TIME 55 9876
TEST_TIME 56 9828
TEST_TIME 57 9811
TEST_TIME 58 9871
TEST_TIME 59 9892
TEST_TIME 60 9877
TEST_TIME 61 9883
TEST_TIME 62 10021
TEST_TIME 63 10052
TEST_TIME 64 9998
TEST_TIME 65 10015
TEST_TIME 66 10048
TEST_TIME 67 10068
TEST_TIME 68 9987
TEST_TIME 69 9875
TEST_TIME 70 9834
TEST_TIME 71 9861
TEST_TIME 72 9780
TEST_TIME 73 9707
TEST_TIME 74 9632
TEST_TIME 75 9646
TEST_TIME 76 9598
TEST_TIME 77 9580
TEST_TIME 78 9550
TEST_TIME 79 9518
TEST_TIME 80 9493
TEST_TIME 81 9502
TEST_TIME 82 9587
TEST_TIME 83 9528
TEST_TIME 84 9539
TEST_TIME 85 9546
TEST_TIME 86 9614
TEST_TIME 87 9590
TEST_TIME 88 9683
TEST_TIME 89 9591
TEST_TIME 90 9567
TEST_TIME 91 9531
TEST_TIME 92 9500
TEST_TIME 93 9443
TEST_TIME 94 9393
TEST_TIME 95 9402
TEST_TIME 96 9339
TEST_TIME 97 9321
TEST_TIME 98 9354
TEST_TIME 99 9315
TEST_TIME 100 9295
TEST_TIME 101 9285
TEST_TIME 102 9297
TEST_TIME 103 9262
TEST_TIME 104 9299
TEST_TIME 105 9364
TEST_TIME 106 9396
TEST_TIME 107 9533
TEST_TIME 108 9536
TEST_TIME 109 9517
TEST_TIME 110 9507
TEST_TIME 111 9421
TEST_TIME 112 9377
TEST_TIME 113 9394
TEST_TIME 114 9346
TEST_TIME 115 9329
TEST_TIME 116 9297
TEST_TIME 117 9227
TEST_TIME 118 9200
TEST_TIME 119 9165
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-02-2023 10:39:21 to 14-02-2023 10:39:25.

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 1514467 1514467 0 0.00 0 24640 12 0 0
doc_list_biud 1514455 1514455 0 0.00 0 40826 41 0 0
sp_add_invoice_to_stock 4976 4860 116 2.33 81 238522 25357 0 116
sp_cancel_adding_invoice 5575 5575 0 0.00 6 109235 1170 0 0
sp_cancel_client_order 1753 1753 0 0.00 6 10903 405 0 0
sp_cancel_customer_reserve 547469 547469 0 0.00 1 40827 99 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 4 2548 109 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 5883 121 0 0
sp_cancel_supplier_invoice 6209 6193 16 0.25 2 25699 1009 0 16
sp_cancel_supplier_order 419 419 0 0.00 12 1614772 162331 0 0
sp_cancel_write_off 9689 9689 0 0.00 2 8339 170 0 0
sp_client_order 20388 20388 0 0.00 5 44517 456 0 0
sp_customer_reserve 459446 459446 0 0.00 0 118693 239 0 0
sp_fill_shopping_cart 31122 31122 0 0.00 1 25091 76 0 0
sp_fill_shopping_cart_clo_res 459417 459417 0 0.00 0 44155 18 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 17530 262 0 0
sp_kill_cost_storno 16321 16321 0 0.00 0 8300 69 0 0
sp_kill_qstorno_ret_qs2qd 1111239 1111239 0 0.00 0 40367 44 0 0
sp_kill_qty_storno 585635 585635 0 0.00 0 40826 99 0 0
sp_lock_selected_doc 20102 20102 0 0.00 0 9174 9 0 0
sp_make_cost_storno 27061 27061 0 0.00 0 11763 81 0 0
sp_multiply_rows_for_pdistr 27061 27061 0 0.00 0 24619 41 0 0
sp_multiply_rows_for_qdistr 20388 20388 0 0.00 0 19005 182 0 0
sp_pay_from_customer 6873 6873 0 0.00 5 11000 161 0 0
sp_pay_to_supplier 5650 5650 0 0.00 6 24646 208 0 0
sp_payment_common 12523 12523 0 0.00 2 6870 47 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 10895 334 0 0
sp_qd_handle_on_invoice_upd_sts 10403 10403 0 0.00 0 26632 483 0 0
sp_qd_handle_on_reserve_upd_sts 9709 9709 0 0.00 0 14094 66 0 0
sp_reserve_write_off 9709 9709 0 0.00 9 14369 275 0 0
sp_supplier_invoice 5329 5329 0 0.00 11 86267 3249 0 0
sp_supplier_order 5410 5410 0 0.00 7 36799 1392 0 0
srv_aggregate_perf_data 118 118 0 0.00 2644 129620 14035 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 663 147043 33885 0 0
srv_find_qd_qs_mism 1043272 1043272 0 0.00 0 14065 9 0 0
srv_make_invnt_saldo 485 485 0 0.00 45 91537 3872 0 0
srv_make_money_saldo 872 872 0 0.00 1 29922 413 0 0
srv_recalc_idx_stat 5 5 0 0.00 77415 429516 239501 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4863 4863 0 0.00 1 20692 854 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 8638 489 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 2576 28 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 694 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 783 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 3230 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 542 26 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 72827 5899 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 2037 17 0 0
t$perf-norm:sp_client_order 20388 20388 0 0.00 0 12824 35 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 17103 33 0 0
t$perf-norm:sp_pay_from_customer 6873 6873 0 0.00 0 5431 17 0 0
t$perf-norm:sp_pay_to_supplier 5650 5650 0 0.00 0 4935 17 0 0
t$perf-norm:sp_reserve_write_off 9709 9709 0 0.00 0 5557 21 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 3103 28 0 0
t$perf-norm:sp_supplier_order 5410 5410 0 0.00 0 4936 29 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 712 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 654 14 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 18788 58 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 281 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 248 98 0 0
v_add_invoice_to_stock 9874 9642 232 2.34 0 2831 23 0 232
v_all_customers 37390 37390 0 0.00 0 80 3 0 0
v_all_suppliers 29702 29702 0 0.00 0 259 4 0 0
v_all_wares 40776 40776 0 0.00 0 251 4 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 932 16 0 0
v_cancel_client_order 3506 3506 0 0.00 0 1309 10 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 450 9 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1915 11 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 467 11 0 0
v_cancel_supplier_invoice 808 776 32 3.96 0 1127 22 0 32
v_cancel_supplier_order 838 838 0 0.00 0 665 10 0 0
v_cancel_write_off 1862 1862 0 0.00 0 2873 23 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 2420 5 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1722 18 0 0
v_max_id_ord_sup 5324 5324 0 0.00 0 9095 13 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 1461 8 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 797 7 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 4220 12 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 188 6 0 0
v_min_id_ord_sup 5324 5324 0 0.00 0 4784 9 0 0
v_min_non_paid_invoice 5650 5650 0 0.00 0 4981 22 0 0
v_min_non_paid_realizn 6873 6873 0 0.00 0 6862 20 0 0
v_our_firm 10138 10138 0 0.00 0 70 4 0 0
v_reserve_write_off 19418 19418 0 0.00 0 3367 9 0 0
x_lock_depdocs_on_canc_invoice 11380 11380 0 0.00 0 18975 122 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5844 335 0 0
x_make_qstorno_1000_1200 5410 5410 0 0.00 2 36729 1240 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 4 86252 3034 0 0
x_make_qstorno_2100_3300 459446 459446 0 0.00 0 118685 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2023 10:39:26 to 14-02-2023 10:39:30.

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
08:07:29 959 8192000000 8933134608 8988962816 4612624 0.056 3 1 2 0 4711936 63536 35776
08:08:31 37414 8192000000 9725607648 10028453888 798636848 8.882 100 81 162 0 826568992 8196784 19735360
08:10:16 19802 8192000000 9778356800 10087751680 845383424 9.354 100 92 184 0 880322928 13340160 21599344
08:11:46 9086 8192000000 9812869008 10135375872 876205392 9.662 100 84 168 0 915380768 20363456 18811920
08:13:03 5488 8192000000 9856392864 10191896576 911488816 10.012 100 77 152 0 963208096 35646192 16073088
08:14:12 3078 8192000000 9921660416 10264055808 952591008 10.416 100 85 165 1 1028256944 58580576 17085360
08:15:19 1763 8192000000 9975237408 10327080960 986082800 10.743 100 93 183 1 1078620464 74272848 18264816
08:16:23 1696 8192000000 9991992160 10354253824 1008632944 10.962 100 88 171 2 1103971840 79302272 16036624
08:17:27 3318 8192000000 10011505360 10376400896 1027380544 11.143 100 92 168 3 1112153312 69488880 15283888
08:18:31 981 8192000000 10017605248 10388344832 1045610720 11.319 100 98 189 1 1131454960 65141744 20702496
08:19:33 4406 8192000000 10028932896 10407510016 1055819520 11.416 100 82 153 2 1136029920 65953568 14256832
08:20:39 2170 8192000000 10036485024 10416562176 1064051696 11.495 100 83 156 0 1141622928 60205312 17365920
08:21:42 1289 8192000000 10041391200 10423541760 1065483904 11.509 100 91 174 3 1153301168 69661168 18156096
08:22:44 1343 8192000000 10048820112 10440376320 1072603440 11.577 100 84 162 1 1161659024 72385696 16669888
08:23:46 1102 8192000000 10063496448 10449973248 1081084528 11.658 100 92 184 0 1172677312 72912432 18680352
08:24:48 1185 8192000000 10069995808 10454503424 1083933008 11.685 100 96 188 0 1177054080 73952064 19169008
08:25:50 2034 8192000000 10050037200 10440695808 1086177488 11.706 100 78 154 4 1158517440 57152448 15187504
08:26:53 1338 8192000000 10063503184 10452914176 1088311392 11.727 100 97 186 0 1179324304 71644640 19368272
08:27:55 1944 8192000000 10059073472 10456342528 1088497104 11.728 100 67 127 3 1176100048 74389792 13213152
08:28:58 1364 8192000000 10057890368 10448707584 1083025712 11.676 100 82 157 1 1167801312 68712736 16062864
08:30:00 1289 8192000000 10028707584 10407194624 1055264864 11.411 100 91 175 1 1139098400 64763552 19069984
08:31:03 877 8192000000 10018300688 10398523392 1053611936 11.395 100 93 167 0 1131113920 60443216 17058768
08:32:05 1131 8192000000 10019898784 10395992064 1046628192 11.328 99 94 173 2 1133968736 68716464 18624080
08:33:07 1040 8192000000 9974803808 10344468480 1018211280 11.055 99 96 176 3 1086417360 48907152 19298928
08:34:09 1652 8192000000 9942478656 10307280896 1004149056 10.919 100 80 150 1 1064712192 45117024 15446112
08:35:12 4226 8192000000 9946615664 10322255872 1015109888 11.025 99 70 127 4 1070009952 40661680 14238384
08:36:16 1111 8192000000 9955690560 10336096256 1031809952 11.186 100 77 143 3 1079146912 31477024 15859936
08:37:20 2222 8192000000 9962560928 10345369600 1042648192 11.290 100 62 113 2 1078247552 23502208 12097152
08:38:33 48897 8192000000 10032688336 10425331712 1067187600 11.525 100 63 121 3 1155520944 75921488 12411856
08:40:26 10705 8192000000 10064633008 10465947648 1085992784 11.705 100 61 113 0 1177925216 81031024 10901408
08:41:42 3801 8192000000 10099548464 10488975360 1101973680 11.856 100 80 157 2 1223910784 105508768 16428336
08:42:50 2486 8192000000 10128925296 10526711808 1112326016 11.954 100 77 149 1 1245341648 117340528 15675104
08:43:56 2517 8192000000 10140232528 10536816640 1127373200 12.097 100 63 119 5 1261766176 121779808 12613168
08:45:01 4671 8192000000 10180221696 10579128320 1136607872 12.184 100 68 132 1 1289212544 138548752 14055920
08:46:19 1755 8192000000 10222789584 10631286784 1148561872 12.296 100 78 151 0 1338221568 172758128 16901568
08:47:25 5557 8192000000 10256565040 10660937728 1158343104 12.388 100 70 140 0 1371910016 198174176 15392736
08:48:44 4812 8192000000 10318995552 10723663872 1178461552 12.576 100 80 155 0 1430139872 233857872 17820448
08:49:58 1323 8192000000 10356289632 10758569984 1177548816 12.567 100 91 176 0 1467147296 269192816 20405664
08:51:03 651 8192000000 10393412832 10795704320 1174241904 12.536 99 98 192 0 1505714416 309191696 22280816
08:52:07 1012 8192000000 10464524608 10879098880 1190486384 12.688 100 98 189 0 1574728608 361892384 22349840
08:53:14 2814 8192000000 10469523744 10894032896 1191409376 12.696 100 85 166 0 1575623456 364506928 19707152
08:54:19 968 8192000000 10506031552 10925387776 1197034944 12.749 100 99 193 1 1613659856 393845856 22779056
08:55:24 944 8192000000 10509941440 10955382784 1201770128 12.793 100 99 193 0 1616907744 393002080 22135536
08:56:26 3186 8192000000 10476054912 10922496000 1179790688 12.588 99 73 141 2 1595967728 399238256 16938784
08:57:31 1159 8192000000 10524428192 10952609792 1202879984 12.803 100 79 153 0 1636989392 415483568 18625840
08:58:34 2240 8192000000 10524191056 10986754048 1194619744 12.726 100 79 154 0 1629482016 416438112 18424160
08:59:39 2670 8192000000 10527320208 10979205120 1176900000 12.561 99 70 137 0 1629282960 434520352 17862608
09:00:59 19285 8192000000 10530960368 10992312320 1189143504 12.675 100 45 90 0 1645270288 444389424 11737360
09:02:29 4718 8192000000 10550144624 10986790912 1169405568 12.491 99 54 106 0 1652268864 469051056 13812240
09:03:44 16827 8192000000 10540638272 11033124864 1146893248 12.280 98 40 80 1 1627208784 468886144 11429392
09:05:18 15557 8192000000 10361889616 10860175360 1148982416 12.300 100 42 82 2 1493817680 334398256 10437008
09:06:40 26445 8192000000 10359013552 10813407232 1139828256 12.214 99 27 54 0 1445032896 298320448 6884192
09:08:17 17671 8192000000 10210157840 10737737728 1112739536 11.958 99 14 28 0 1338073808 221429696 3904576
09:09:37 27113 8192000000 10072063328 10508038144 1101972192 11.856 98 20 38 0 1151141568 45042400 4126976
09:11:12 29124 8192000000 10111569472 10561224704 1144876304 12.261 100 20 39 0 1220864752 71167968 4820480
09:13:32 100429 8192000000 10114532528 10558468096 1133878064 12.158 99 26 52 0 1214136560 74344016 5914480
09:16:17 96673 8192000000 10080429216 10540601344 1122093792 12.047 99 37 67 1 1172273344 43584128 6595424
09:19:25 147041 8192000000 10082958000 10518224896 1084479376 11.690 95 60 115 3 1178356432 81590192 12286864
09:22:53 20983 8192000000 10082816128 10516099072 1115593648 11.985 98 29 58 0 1170388976 48931840 5863488
09:24:29 2263 8192000000 10176990320 10613108736 1138874064 12.205 100 70 136 1 1280539584 126978176 14687344
09:25:37 35604 8192000000 10076050528 10544824320 1126636752 12.090 99 8 16 0 1175200656 46529664 2034240
09:27:45 15864 8192000000 10076454528 10541191168 1128515456 12.107 99 41 77 1 1178480432 40256400 9708576
09:29:03 75753 8192000000 10114771904 10580246528 1137852432 12.195 100 15 30 0 1203960960 62668384 3440144
09:31:34 65295 8192000000 10112606688 10566057984 1129772224 12.119 99 16 32 0 1204721904 71478160 3471520
09:33:53 62709 8192000000 10129910432 10633912320 1128684880 12.109 99 22 43 0 1217095280 83410768 4999632
09:35:59 39286 8192000000 10142982208 10614779904 1152367808 12.332 100 25 50 0 1245106256 87459824 5278624
09:37:47 54077 8192000000 10149146624 10608214016 1116914784 11.998 97 24 48 0 1225524016 103374000 5235232
09:39:43 28130 8192000000 10097615920 10551808000 1138932656 12.205 100 33 63 0 1195014960 49749968 6332336
09:41:13 46960 8192000000 10130596240 10560528384 1146909872 12.280 99 34 63 1 1205429072 50853408 7665792
09:43:01 29346 8192000000 10134538080 10604998656 1158183712 12.386 100 25 50 0 1249906528 86676912 5045904
09:44:31 24694 8192000000 10113179856 10545090560 1153434704 12.342 99 52 101 1 1202613168 38853632 10324832
09:45:59 80487 8192000000 10121266432 10562981888 1151715552 12.326 100 44 86 1 1223136576 61836240 9584784
09:48:22 37102 8192000000 10086936880 10592432128 1132264432 12.143 98 20 40 0 1211883856 75701840 3917584
09:50:02 31725 8192000000 10051726960 10522083328 1103843632 11.874 96 14 28 0 1137217088 30399760 2973696
09:51:35 92718 8192000000 10071847600 10589327360 1107357408 11.907 97 8 16 0 1191435568 82157392 1920768
09:54:09 29167 8192000000 10052385008 10491740160 1090168512 11.744 96 22 44 0 1136473312 41426112 4878688
09:55:43 28603 8192000000 10108603552 10541813760 1130457792 12.126 100 41 80 0 1194462752 55592528 8412432
09:57:33 42420 8192000000 10140494544 10573062144 1079196832 11.640 94 31 62 0 1174053440 88361536 6495072
09:59:19 53177 8192000000 10079269376 10531192832 1107933712 11.913 98 20 38 0 1165803664 54240192 3629760
10:01:19 18697 8192000000 10121680352 10584240128 1122546656 12.051 99 38 72 1 1201619184 72032528 7040000
10:02:38 12613 8192000000 10166937504 10598281216 1125530256 12.079 99 48 96 0 1247227120 111806736 9890128
10:04:17 73838 8192000000 10030050896 10487353344 1060560320 11.462 94 23 44 0 1129720224 64607472 4552432
10:06:31 112172 8192000000 10048800208 10512343040 1125045536 12.075 100 30 58 1 1180744080 49743472 5955072
10:10:21 69149 8192000000 10060356224 10523009024 1105919568 11.894 97 14 28 0 1149027424 40344576 2763280
10:13:44 27023 8192000000 10109747872 10552266752 1120912528 12.036 98 22 45 1 1196045920 71056320 4077072
10:15:11 36898 8192000000 10104523440 10551418880 1129683760 12.118 99 30 62 0 1198229568 60770896 7774912
10:16:53 18738 8192000000 10159473680 10603999232 1153023936 12.338 100 28 51 0 1258664784 99800448 5840400
10:18:16 15189 8192000000 10231527104 10663419904 1145049056 12.263 99 53 106 0 1315020032 158215184 11755792
10:19:44 40794 8192000000 10204167856 10654474240 1143072544 12.244 99 26 53 0 1280163728 131117200 5973984
10:21:35 20086 8192000000 10245079088 10679160832 1153762928 12.345 100 37 74 0 1324168336 160996640 9408768
10:23:02 34763 8192000000 10181818192 10627338240 1127335680 12.096 98 39 75 1 1279664416 143914592 8414144
10:24:39 69632 8192000000 10076014240 10516803584 1134217360 12.161 99 12 24 0 1205926288 69061120 2647808
10:26:53 20273 8192000000 10099912272 10532278272 1144892640 12.262 100 50 89 0 1205466048 50230192 10343216
10:28:15 101887 8192000000 10094106320 10537033728 1139873232 12.214 99 27 54 1 1194729248 49285632 5570384
10:31:03 39406 8192000000 10049706352 10521923584 1138305232 12.200 100 9 16 0 1171186656 31287744 1593680
10:32:45 64343 8192000000 10061173408 10516213760 1116776864 11.997 98 20 39 0 1137676640 16737152 4162624
10:34:50 62875 8192000000 10103332864 10574557184 1126664464 12.090 98 19 38 0 1182153408 51491472 3997472

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-02-2023 10:39:32 to 14-02-2023 10:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-02-2023 10:39:49 to 14-02-2023 10:39:51.

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          216200
MON$OLDEST_ACTIVE               220779
MON$OLDEST_SNAPSHOT             220779
MON$NEXT_TRANSACTION            220779
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                       2066976
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2900 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2900 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2900 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2900 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Feb 14 10:37:29 2023
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Feb 14 10:37:35 2023
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Feb 14 10:37:37 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Feb 14 10:37:37 2023
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

14.02.23 10:39:53. 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-14 10:39:55 No severe PSQL-related problems occured
14.02.23 10:39:57. End of report.