Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 13: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 14968364 kB
MemAvailable 30124924 kB
Buffers 0 kB
Cached 16758312 kB
SwapCached 3588 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-28 13:37:23
DTS_END                         2022-12-28 13:37:23.7350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50002
TRN_ID                          221647
ATT_ID                          645
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: 8553.78 23783 1026454
customer order: creation 167.67 472 20120
customer order: refuse 13.54 374 1625
order to supplier: creation 45.03 1455 5403
order to supplier: removal 3.29 149409 395
invoice (draft): creation 44.84 3398 5381
invoice (draft): removal 46.24 1095 5549
invoice accept: apply 40.75 27131 4890
invoice accept: cancel 41.38 1201 4966
customer reserve: creation 3822.60 258 458712
customer reserve: removal 4044.15 94 485298
realization accept: apply 81.96 268 9835
realization accept: cancel 74.38 173 8926
payment from customer: creation 59.02 170 7082
payment from customer: removal 5.28 105 634
payment to supplier: creation 47.85 208 5742
payment to supplier: removal 3.43 149 412
service: total inventory turnovers 4.16 3525 499
service: total monetary turnovers 7.12 384 854
service: aggregate perf. data 1.05 14061 126
service: refresh index statistics 0.04 271729 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2022 13:38:52 to 28-12-2022 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8460
TEST_TIME 2 6526
TEST_TIME 3 7610
TEST_TIME 4 6861
TEST_TIME 5 6242
TEST_TIME 6 6066
TEST_TIME 7 6031
TEST_TIME 8 6246
TEST_TIME 9 6425
TEST_TIME 10 6605
TEST_TIME 11 6789
TEST_TIME 12 6893
TEST_TIME 13 6872
TEST_TIME 14 7083
TEST_TIME 15 7150
TEST_TIME 16 7245
TEST_TIME 17 7330
TEST_TIME 18 7465
TEST_TIME 19 7421
TEST_TIME 20 7824
TEST_TIME 21 7859
TEST_TIME 22 8046
TEST_TIME 23 8228
TEST_TIME 24 8473
TEST_TIME 25 8681
TEST_TIME 26 9137
TEST_TIME 27 9357
TEST_TIME 28 9588
TEST_TIME 29 9637
TEST_TIME 30 9898
TEST_TIME 31 10154
TEST_TIME 32 10402
TEST_TIME 33 10644
TEST_TIME 34 10632
TEST_TIME 35 10560
TEST_TIME 36 10397
TEST_TIME 37 10284
TEST_TIME 38 10315
TEST_TIME 39 10185
TEST_TIME 40 10001
TEST_TIME 41 9861
TEST_TIME 42 9841
TEST_TIME 43 9702
TEST_TIME 44 9567
TEST_TIME 45 9629
TEST_TIME 46 9529
TEST_TIME 47 9519
TEST_TIME 48 9423
TEST_TIME 49 9361
TEST_TIME 50 9246
TEST_TIME 51 9170
TEST_TIME 52 9131
TEST_TIME 53 9167
TEST_TIME 54 9195
TEST_TIME 55 9226
TEST_TIME 56 9110
TEST_TIME 57 8988
TEST_TIME 58 8969
TEST_TIME 59 9030
TEST_TIME 60 8960
TEST_TIME 61 8935
TEST_TIME 62 9115
TEST_TIME 63 9097
TEST_TIME 64 9115
TEST_TIME 65 9120
TEST_TIME 66 9139
TEST_TIME 67 9133
TEST_TIME 68 9103
TEST_TIME 69 9109
TEST_TIME 70 9105
TEST_TIME 71 9048
TEST_TIME 72 8995
TEST_TIME 73 8919
TEST_TIME 74 8863
TEST_TIME 75 8856
TEST_TIME 76 8794
TEST_TIME 77 8761
TEST_TIME 78 8697
TEST_TIME 79 8648
TEST_TIME 80 8609
TEST_TIME 81 8733
TEST_TIME 82 8729
TEST_TIME 83 8749
TEST_TIME 84 8723
TEST_TIME 85 8733
TEST_TIME 86 8754
TEST_TIME 87 8790
TEST_TIME 88 8854
TEST_TIME 89 8872
TEST_TIME 90 8867
TEST_TIME 91 8870
TEST_TIME 92 8822
TEST_TIME 93 8752
TEST_TIME 94 8763
TEST_TIME 95 8737
TEST_TIME 96 8696
TEST_TIME 97 8674
TEST_TIME 98 8696
TEST_TIME 99 8710
TEST_TIME 100 8688
TEST_TIME 101 8705
TEST_TIME 102 8733
TEST_TIME 103 8722
TEST_TIME 104 8731
TEST_TIME 105 8716
TEST_TIME 106 8711
TEST_TIME 107 8753
TEST_TIME 108 8773
TEST_TIME 109 8741
TEST_TIME 110 8680
TEST_TIME 111 8657
TEST_TIME 112 8690
TEST_TIME 113 8742
TEST_TIME 114 8695
TEST_TIME 115 8670
TEST_TIME 116 8615
TEST_TIME 117 8594
TEST_TIME 118 8580
TEST_TIME 119 8626
TEST_TIME 120 8575

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 13:38:55 to 28-12-2022 13:38:59.

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 1447783 1447783 0 0.00 0 27938 13 0 0
doc_list_biud 1447773 1447773 0 0.00 0 80298 38 0 0
sp_add_invoice_to_stock 4980 4890 90 1.80 30 248870 27131 0 90
sp_cancel_adding_invoice 4966 4966 0 0.00 5 81118 1201 0 0
sp_cancel_client_order 1625 1625 0 0.00 6 5583 374 0 0
sp_cancel_customer_reserve 485298 485298 0 0.00 1 26762 94 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 1903 106 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 6953 149 0 0
sp_cancel_supplier_invoice 5556 5549 7 0.12 2 80356 1096 0 7
sp_cancel_supplier_order 395 395 0 0.00 10 1231192 149409 0 0
sp_cancel_write_off 8926 8926 0 0.00 2 11319 173 0 0
sp_client_order 20120 20120 0 0.00 5 27727 473 0 0
sp_customer_reserve 458712 458712 0 0.00 0 59353 259 0 0
sp_fill_shopping_cart 30892 30892 0 0.00 1 24578 83 0 0
sp_fill_shopping_cart_clo_res 458673 458673 0 0.00 0 11348 20 0 0
sp_get_clo_for_invoice 4842 4842 0 0.00 0 35650 359 0 0
sp_kill_cost_storno 14938 14938 0 0.00 0 27925 79 0 0
sp_kill_qstorno_ret_qs2qd 985466 985466 0 0.00 0 80290 43 0 0
sp_kill_qty_storno 521431 521431 0 0.00 0 80294 97 0 0
sp_lock_selected_doc 20191 20191 0 0.00 0 8169 7 0 0
sp_make_cost_storno 27499 27499 0 0.00 0 17116 83 0 0
sp_multiply_rows_for_pdistr 27497 27497 0 0.00 0 17075 40 0 0
sp_multiply_rows_for_qdistr 20119 20119 0 0.00 0 18393 187 0 0
sp_pay_from_customer 7082 7082 0 0.00 5 16386 170 0 0
sp_pay_to_supplier 5742 5742 0 0.00 6 18399 209 0 0
sp_payment_common 12823 12823 0 0.00 2 16370 47 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 5498 308 0 0
sp_qd_handle_on_invoice_upd_sts 9803 9803 0 0.00 0 27851 495 0 0
sp_qd_handle_on_reserve_upd_sts 9835 9835 0 0.00 0 12805 57 0 0
sp_reserve_write_off 9835 9835 0 0.00 8 16662 268 0 0
sp_supplier_invoice 5381 5381 0 0.00 10 113064 3399 0 0
sp_supplier_order 5403 5403 0 0.00 7 65111 1456 0 0
srv_aggregate_perf_data 126 126 0 0.00 2349 104652 14061 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 791 125955 37939 0 0
srv_find_qd_qs_mism 977680 977680 0 0.00 0 11295 10 0 0
srv_make_invnt_saldo 499 499 0 0.00 63 113104 3525 0 0
srv_make_money_saldo 854 854 0 0.00 1 38070 385 0 0
srv_recalc_idx_stat 5 5 0 0.00 34024 449235 271729 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 1 20415 813 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 6097 364 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 16674 42 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1468 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 5201 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1209 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1785 46 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 101963 6328 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 4539 22 0 0
t$perf-norm:sp_client_order 20120 20120 0 0.00 0 11756 34 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 939 12 0 0
t$perf-norm:sp_pay_from_customer 7082 7082 0 0.00 0 9524 22 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 9141 21 0 0
t$perf-norm:sp_reserve_write_off 9835 9835 0 0.00 0 11038 25 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 8149 28 0 0
t$perf-norm:sp_supplier_order 5403 5403 0 0.00 0 8647 30 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 614 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 258 9 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1316 17 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 1875 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 183 50 0 0
v_add_invoice_to_stock 9842 9662 180 1.82 0 5092 23 0 180
v_all_customers 37267 37267 0 0.00 0 151 3 0 0
v_all_suppliers 30098 30098 0 0.00 0 163 4 0 0
v_all_wares 40234 40234 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 5627 25 0 0
v_cancel_client_order 3250 3250 0 0.00 0 1399 9 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 803 10 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2954 13 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3540 18 0 0
v_cancel_supplier_invoice 766 752 14 1.82 0 924 22 0 14
v_cancel_supplier_order 790 790 0 0.00 0 666 9 0 0
v_cancel_write_off 2066 2066 0 0.00 0 4967 27 0 0
v_max_id_clo_ord 5401 5401 0 0.00 0 224 5 0 0
v_max_id_clo_res 954 954 0 0.00 0 4846 22 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 4884 8 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 1403 8 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 1733 8 0 0
v_min_id_clo_ord 5401 5401 0 0.00 0 4562 9 0 0
v_min_id_clo_res 954 954 0 0.00 0 1537 14 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 8071 17 0 0
v_min_non_paid_invoice 5741 5741 0 0.00 0 8328 22 0 0
v_min_non_paid_realizn 7082 7082 0 0.00 0 8528 19 0 0
v_our_firm 10036 10036 0 0.00 0 157 4 0 0
v_reserve_write_off 19668 19668 0 0.00 0 5623 11 0 0
x_lock_depdocs_on_canc_invoice 10139 10139 0 0.00 0 18626 134 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 14088 371 0 0
x_make_qstorno_1000_1200 5403 5403 0 0.00 2 65050 1300 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 6 106726 3153 0 0
x_make_qstorno_2100_3300 458712 458712 0 0.00 0 59288 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 13:39:00 to 28-12-2022 13:39:04.

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
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 26325 8192000000 9710650560 10004721664 785231424 8.746 100 86 172 0 814269040 7834240 21203376
11:10:08 21199 8192000000 9759682768 10064224256 821966832 9.118 100 87 174 0 855439568 12582176 20890560
11:11:34 12675 8192000000 9792177840 10110173184 858335120 9.484 100 81 162 0 900732720 23280384 19117216
11:12:52 3856 8192000000 9861526416 10189385728 912348624 10.021 100 81 161 0 976800208 47254752 17196832
11:14:02 4137 8192000000 9940235696 10282295296 956737840 10.457 100 88 174 0 1050312848 74572944 19002064
11:15:08 1587 8192000000 9966573744 10321379328 996686160 10.846 100 92 179 1 1084121920 69180656 18255104
11:16:12 1292 8192000000 9984265184 10345918464 1023787568 11.109 100 89 167 2 1105876208 65679664 16408976
11:17:16 931 8192000000 10017594528 10390564864 1052210816 11.382 100 98 187 4 1139989216 69570208 18208192
11:18:17 815 8192000000 10020921024 10397429760 1065623168 11.510 100 94 180 3 1143459104 59707424 18128512
11:19:19 1090 8192000000 10024147120 10405740544 1073102016 11.582 100 87 160 1 1145335632 56347552 15886064
11:20:22 2021 8192000000 10043024528 10434932736 1077255424 11.621 100 75 146 0 1166251760 74050288 14946048
11:21:25 757 8192000000 10046114064 10435616768 1079950160 11.647 100 80 155 0 1172000368 77028816 15021392
11:22:31 1154 8192000000 10041694880 10430349312 1087009408 11.714 100 92 178 3 1164037024 60467136 16560480
11:23:32 2859 8192000000 10054122016 10440679424 1084510112 11.690 100 73 145 1 1167714752 69126592 14078048
11:24:36 1025 8192000000 10075906816 10466250752 1090240080 11.745 100 97 183 1 1197777664 88114480 19423104
11:25:38 1197 8192000000 10082316000 10477334528 1094187872 11.782 100 97 177 2 1199013008 88061936 16763200
11:26:40 1142 8192000000 10075539120 10467299328 1095219472 11.792 100 97 185 2 1196334624 82487888 18627264
11:27:43 1076 8192000000 10050844080 10436190208 1091522160 11.757 100 93 176 2 1174348352 66276784 16549408
11:28:44 1001 8192000000 10038130432 10423869440 1080131552 11.649 100 93 177 1 1161996960 63546080 18319328
11:29:47 1281 8192000000 10034468512 10429779968 1071524176 11.567 99 86 165 3 1155782528 66470880 17787472
11:30:49 1040 8192000000 10007773520 10383904768 1054377200 11.403 99 91 175 0 1129926016 58018864 17529952
11:31:51 7197 8192000000 9973245056 10352156672 1021933168 11.091 97 67 127 2 1093618176 58307008 13378000
11:32:59 10401 8192000000 9993463552 10380828672 1034863040 11.215 99 60 119 1 1112453216 65880160 11710016
11:34:10 1022 8192000000 9956276560 10331021312 1016132192 11.035 98 77 139 0 1074976144 43786496 15057456
11:35:11 2432 8192000000 9954038736 10334363648 1036943760 11.235 100 64 117 1 1077650096 27314320 13392016
11:36:14 2177 8192000000 9985052800 10368073728 1044441856 11.307 99 74 144 2 1111226336 51470016 15314464
11:37:18 845 8192000000 9996198144 10375839744 1056687296 11.425 100 92 162 2 1112122736 38904784 16530656
11:38:24 25954 8192000000 10029027792 10418077696 1086418160 11.709 100 69 132 0 1148387488 48656032 13313296
11:39:59 2287 8192000000 10076735296 10462367744 1099590560 11.834 100 87 165 2 1197556128 81135632 16829936
11:41:06 11424 8192000000 10087349744 10478256128 1111502432 11.947 100 77 153 3 1206865056 80280304 15082320
11:42:21 7767 8192000000 10118926432 10514632704 1126023952 12.084 100 62 122 1 1240302240 100981584 13296704
11:43:41 1251 8192000000 10175195520 10570960896 1140074752 12.216 100 92 170 2 1286972624 130309424 16588448
11:44:54 5010 8192000000 10197846864 10612641792 1146519200 12.277 100 73 140 2 1315332144 153111168 15701776
11:46:03 1782 8192000000 10207667984 10613702656 1153169632 12.339 100 71 140 0 1323493568 154048640 16275296
11:47:10 3167 8192000000 10236019360 10639478784 1165419456 12.454 100 82 155 1 1348525440 166730400 16375584
11:48:16 2377 8192000000 10266740304 10671939584 1173352416 12.528 100 84 158 1 1387208768 196483456 17372896
11:49:22 4265 8192000000 10321802848 10726916096 1179480736 12.585 100 84 159 1 1430115392 233161376 17473280
11:50:28 1036 8192000000 10348055744 10753880064 1178867904 12.580 99 87 162 0 1456149888 258390512 18891472
11:51:32 1666 8192000000 10351134720 10752479232 1170509504 12.502 100 85 158 0 1450785056 262079104 18196448
11:52:38 2789 8192000000 10393168352 10806358016 1189818096 12.682 100 83 165 0 1508010768 299926208 18266464
11:53:45 1570 8192000000 10420524672 10848858112 1194724032 12.727 100 83 158 0 1529956176 316943312 18288832
11:54:51 2062 8192000000 10414753840 10842370048 1192472352 12.706 100 92 177 0 1515255504 302213552 20569600
11:55:59 808 8192000000 10463331776 10893426688 1202114416 12.796 100 95 187 0 1569239696 343615344 23509936
11:57:02 785 8192000000 10445218976 10885341184 1183512416 12.623 99 95 186 0 1550258928 344660384 22086128
11:58:06 4550 8192000000 10449479408 10883555328 1176603632 12.559 100 62 120 0 1549078816 357101856 15373328
11:59:19 1974 8192000000 10484217872 10932412416 1180302432 12.593 100 71 142 1 1584847520 386412448 18132640
12:00:29 22250 8192000000 10437903408 10924969984 1178196912 12.573 100 47 92 0 1547688608 356811808 12679888
12:01:57 11176 8192000000 10411566112 10875334656 1163452752 12.436 100 40 80 1 1513291168 339374128 10464288
12:03:10 24035 8192000000 10372305312 10855120896 1164045280 12.441 99 37 72 0 1468701760 295359264 9297216
12:05:09 10645 8192000000 10291156976 10763567104 1137927024 12.196 98 34 66 1 1384172160 237085248 9159888
12:06:26 24644 8192000000 10325773808 10817396736 1160598320 12.409 100 28 56 0 1436171056 268295056 7277680
12:08:05 29112 8192000000 10249870208 10751254528 1164883792 12.449 100 28 52 0 1376025824 204591360 6550672
12:09:34 32708 8192000000 10217120368 10703831040 1161880704 12.421 100 18 36 0 1332621424 166509168 4231552
12:11:08 23459 8192000000 10095660128 10524672000 1124245664 12.067 98 40 74 1 1208421136 76326416 7849056
12:12:32 54266 8192000000 10110961008 10537066496 1130980384 12.131 99 55 91 4 1209759696 69291360 9487952
12:14:28 69587 8192000000 10083033616 10564505600 1128841056 12.110 99 21 40 0 1177130464 43971360 4318048
12:16:37 67268 8192000000 10099979120 10538688512 1131463440 12.135 99 26 52 0 1176846208 40236080 5146688
12:19:05 77933 8192000000 10101276656 10572013568 1109978240 11.932 97 19 38 0 1193394128 79159744 4256144
12:21:24 125955 8192000000 10054403984 10539511808 1091761184 11.759 96 13 26 1 1163294592 68601568 2931840
12:24:31 76305 8192000000 10051455120 10486136832 1083720240 11.683 95 46 92 0 1111011456 17601040 9690176
12:26:50 21406 8192000000 10066210592 10512543744 1138520512 12.202 100 21 42 0 1170526000 27242560 4762928
12:28:23 11682 8192000000 10118940752 10551382016 1135198000 12.170 99 53 101 2 1213183808 66880928 11104880
12:29:38 42511 8192000000 10155884784 10627219456 1137581232 12.193 99 30 61 0 1235940016 92070928 6287856
12:31:42 48493 8192000000 10072902688 10556035072 1130898352 12.130 99 19 36 0 1203063056 68013808 4150896
12:34:30 66852 8192000000 10100338368 10571472896 1151737232 12.326 100 11 20 1 1216900448 62362768 2800448
12:36:39 58616 8192000000 10078248000 10514636800 1084897216 11.694 95 18 36 0 1140737968 52519088 3321664
12:38:39 52654 8192000000 10113159632 10593329152 1132886976 12.149 99 14 28 0 1214346384 78461728 2997680
12:40:32 114120 8192000000 10073724464 10558672896 1115416176 11.984 97 15 30 0 1158102240 39830848 2855216
12:43:27 34721 8192000000 10078922976 10518511616 1134045360 12.159 99 26 52 0 1176911360 36973888 5892112
12:45:03 13474 8192000000 10097668896 10542837760 1141754080 12.232 100 52 104 0 1192033920 39826336 10453504
12:46:18 90033 8192000000 10091080816 10527936512 1087637680 11.720 95 41 82 0 1138375648 43119472 7618496
12:48:50 7754 8192000000 10103025472 10514771968 1121565984 12.042 100 72 142 1 1192760320 57786688 13407648
12:49:58 85093 8192000000 10053220768 10478006272 1084107216 11.687 97 56 112 0 1152922352 58336144 10478992
12:52:25 38289 8192000000 10057938816 10520735744 1106568304 11.900 99 21 42 0 1168620144 57966832 4085008
12:54:04 68053 8192000000 10051794704 10524798976 1101249168 11.849 98 10 20 0 1146379936 42958528 2172240
12:56:48 61078 8192000000 10136169616 10585427968 1118481408 12.013 99 16 32 0 1220198096 97552560 4164128
12:58:50 25980 8192000000 10063945248 10500362240 1113280000 11.963 98 40 77 0 1143073968 22092640 7701328
13:00:27 25576 8192000000 10113706160 10558640128 1128798880 12.110 99 36 72 0 1194760864 58419296 7542688
13:01:52 37671 8192000000 10156586880 10613714944 1150487728 12.314 100 32 64 0 1241026144 83685744 6852672
13:03:32 84313 8192000000 10100418080 10551533568 1142408208 12.238 100 14 29 0 1233145904 87619536 3118160
13:05:56 41291 8192000000 10101578480 10581397504 1115054368 11.980 98 26 50 0 1165072784 44413712 5604704
13:07:38 18839 8192000000 10090532752 10517991424 1127479248 12.098 99 47 92 0 1160636528 24210960 8946320
13:09:01 101571 8192000000 10085203200 10535370752 1119136208 12.019 99 28 55 0 1184221696 59870768 5214720
13:11:43 66045 8192000000 10067449296 10567503872 1132934400 12.149 100 5 10 0 1177835424 44255536 645488
13:14:32 59526 8192000000 10079872656 10534977536 1139206400 12.208 100 31 60 0 1194497664 48885424 6405840
13:16:32 23943 8192000000 10095965104 10554507264 1119219488 12.020 99 49 96 2 1186902320 57707648 9975184
13:18:02 94447 8192000000 10072426704 10532323328 1062202096 11.478 93 25 49 0 1115535744 48468336 4865312
13:20:36 36326 8192000000 10090877712 10522914816 1106629968 11.901 98 34 68 0 1160661456 46751744 7279744
13:22:19 38615 8192000000 10128697024 10572124160 1103178448 11.868 97 40 80 0 1200558320 89413408 7966464
13:24:13 68324 8192000000 10048252736 10476371968 1100485296 11.842 97 30 60 1 1134614400 28232624 5896480
13:26:22 19847 8192000000 10163503424 10585612288 1143039392 12.244 100 56 112 0 1241668016 87675872 10952752
13:27:42 123518 8192000000 10133463712 10596696064 1132106192 12.141 99 32 64 1 1233944000 94086576 7751232
13:30:54 45199 8192000000 10047841200 10496139264 1139584608 12.212 100 5 10 0 1173453760 33106400 762752
13:32:46 67815 8192000000 10096661152 10547978240 1105992160 11.894 97 14 28 0 1156571088 47359888 3219040
13:34:54 58005 8192000000 10094853824 10587017216 1110128000 11.934 98 7 14 0 1177261904 65552016 1581888
13:36:52 34068 8192000000 9887977200 10299146240 940917472 10.302 84 19 36 1 1000301760 55430080 3954208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-12-2022 13:39:06 to 28-12-2022 13:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2022 13:39:23 to 28-12-2022 13:39:25.

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          219589
MON$OLDEST_ACTIVE               222071
MON$OLDEST_SNAPSHOT             222071
MON$NEXT_TRANSACTION            222071
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                       2048736
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             776
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 ---
:648: 
:649: mscs-test-161.budzdorov.ru    Wed Dec 28 13:37:32 2022
:650:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:651: 
:652: 
:653: mscs-test-161.budzdorov.ru    Wed Dec 28 13:37:33 2022
:654:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:655: 
--- end of diff output ---

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

28.12.22 13:39:26. 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-28 13:39:29 No severe PSQL-related problems occured
28.12.22 13:39:30. End of report.