Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.2022 10:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14605160 kB
MemAvailable 30141976 kB
Buffers 0 kB
Cached 17113532 kB
SwapCached 4384 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-08 10:37:19
DTS_END                         2022-12-08 10:37:19.2670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55122
TRN_ID                          227709
ATT_ID                          617
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: 8971.06 26598 1076527
customer order: creation 181.51 463 21781
customer order: refuse 15.94 393 1913
order to supplier: creation 48.33 1374 5799
order to supplier: removal 3.48 162604 417
invoice (draft): creation 48.12 3252 5774
invoice (draft): removal 49.36 1044 5923
invoice accept: apply 43.54 25175 5225
invoice accept: cancel 43.90 1281 5268
customer reserve: creation 3977.48 243 477298
customer reserve: removal 4265.48 105 511858
realization accept: apply 86.48 277 10378
realization accept: cancel 72.13 185 8655
payment from customer: creation 61.59 179 7391
payment from customer: removal 5.79 136 695
payment to supplier: creation 50.84 187 6101
payment to supplier: removal 3.40 128 408
service: total inventory turnovers 4.38 3544 525
service: total monetary turnovers 8.29 319 995
service: aggregate perf. data 0.99 14458 119
service: refresh index statistics 0.03 316614 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2022 10:39:44 to 08-12-2022 10:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9325
TEST_TIME 2 5633
TEST_TIME 3 5384
TEST_TIME 4 6001
TEST_TIME 5 5779
TEST_TIME 6 5944
TEST_TIME 7 5740
TEST_TIME 8 5714
TEST_TIME 9 5637
TEST_TIME 10 5622
TEST_TIME 11 5968
TEST_TIME 12 6229
TEST_TIME 13 6197
TEST_TIME 14 6984
TEST_TIME 15 7010
TEST_TIME 16 7076
TEST_TIME 17 7215
TEST_TIME 18 7440
TEST_TIME 19 7467
TEST_TIME 20 7576
TEST_TIME 21 7611
TEST_TIME 22 7726
TEST_TIME 23 7862
TEST_TIME 24 7950
TEST_TIME 25 8242
TEST_TIME 26 8241
TEST_TIME 27 8543
TEST_TIME 28 8507
TEST_TIME 29 8591
TEST_TIME 30 9141
TEST_TIME 31 9494
TEST_TIME 32 9549
TEST_TIME 33 9807
TEST_TIME 34 10682
TEST_TIME 35 10920
TEST_TIME 36 10856
TEST_TIME 37 10908
TEST_TIME 38 10770
TEST_TIME 39 10629
TEST_TIME 40 10493
TEST_TIME 41 10310
TEST_TIME 42 10307
TEST_TIME 43 10311
TEST_TIME 44 10185
TEST_TIME 45 10267
TEST_TIME 46 10132
TEST_TIME 47 10054
TEST_TIME 48 10002
TEST_TIME 49 9978
TEST_TIME 50 9955
TEST_TIME 51 9962
TEST_TIME 52 9931
TEST_TIME 53 9860
TEST_TIME 54 9814
TEST_TIME 55 9822
TEST_TIME 56 9706
TEST_TIME 57 9626
TEST_TIME 58 9536
TEST_TIME 59 9502
TEST_TIME 60 9467
TEST_TIME 61 9475
TEST_TIME 62 9431
TEST_TIME 63 9427
TEST_TIME 64 9427
TEST_TIME 65 9401
TEST_TIME 66 9403
TEST_TIME 67 9392
TEST_TIME 68 9369
TEST_TIME 69 9467
TEST_TIME 70 9565
TEST_TIME 71 9564
TEST_TIME 72 9563
TEST_TIME 73 9840
TEST_TIME 74 9814
TEST_TIME 75 9731
TEST_TIME 76 9728
TEST_TIME 77 9663
TEST_TIME 78 9625
TEST_TIME 79 9566
TEST_TIME 80 9535
TEST_TIME 81 9443
TEST_TIME 82 9456
TEST_TIME 83 9419
TEST_TIME 84 9433
TEST_TIME 85 9471
TEST_TIME 86 9460
TEST_TIME 87 9472
TEST_TIME 88 9448
TEST_TIME 89 9453
TEST_TIME 90 9462
TEST_TIME 91 9526
TEST_TIME 92 9542
TEST_TIME 93 9460
TEST_TIME 94 9384
TEST_TIME 95 9335
TEST_TIME 96 9288
TEST_TIME 97 9240
TEST_TIME 98 9251
TEST_TIME 99 9236
TEST_TIME 100 9253
TEST_TIME 101 9209
TEST_TIME 102 9193
TEST_TIME 103 9222
TEST_TIME 104 9226
TEST_TIME 105 9181
TEST_TIME 106 9275
TEST_TIME 107 9341
TEST_TIME 108 9296
TEST_TIME 109 9314
TEST_TIME 110 9325
TEST_TIME 111 9346
TEST_TIME 112 9290
TEST_TIME 113 9221
TEST_TIME 114 9195
TEST_TIME 115 9167
TEST_TIME 116 9146
TEST_TIME 117 9139
TEST_TIME 118 9102
TEST_TIME 119 9057
TEST_TIME 120 8988

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2022 10:39:48 to 08-12-2022 10:39:51.

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 1518242 1518242 0 0.00 0 18055 14 0 0
doc_list_biud 1518237 1518237 0 0.00 0 57569 41 0 0
sp_add_invoice_to_stock 5349 5225 124 2.31 52 219427 25175 0 124
sp_cancel_adding_invoice 5268 5268 0 0.00 7 82114 1282 0 0
sp_cancel_client_order 1913 1913 0 0.00 6 11802 394 0 0
sp_cancel_customer_reserve 511858 511858 0 0.00 1 17365 105 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 7295 137 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 4626 128 0 0
sp_cancel_supplier_invoice 5936 5923 13 0.21 3 57578 1045 0 13
sp_cancel_supplier_order 417 417 0 0.00 9 1696527 162605 0 0
sp_cancel_write_off 8655 8655 0 0.00 2 16273 185 0 0
sp_client_order 21781 21781 0 0.00 5 17698 464 0 0
sp_customer_reserve 477298 477298 0 0.00 0 137944 243 0 0
sp_fill_shopping_cart 33350 33350 0 0.00 1 17881 85 0 0
sp_fill_shopping_cart_clo_res 477273 477273 0 0.00 0 19074 19 0 0
sp_get_clo_for_invoice 5196 5196 0 0.00 0 24503 302 0 0
sp_kill_cost_storno 15026 15026 0 0.00 0 8305 77 0 0
sp_kill_qstorno_ret_qs2qd 1038711 1038711 0 0.00 0 57567 46 0 0
sp_kill_qty_storno 549607 549607 0 0.00 0 57569 105 0 0
sp_lock_selected_doc 21734 21734 0 0.00 0 11308 10 0 0
sp_make_cost_storno 29065 29065 0 0.00 0 17953 77 0 0
sp_multiply_rows_for_pdistr 29065 29065 0 0.00 0 9423 40 0 0
sp_multiply_rows_for_qdistr 21781 21781 0 0.00 0 17688 185 0 0
sp_pay_from_customer 7391 7391 0 0.00 5 9254 180 0 0
sp_pay_to_supplier 6101 6101 0 0.00 6 9090 188 0 0
sp_payment_common 13492 13492 0 0.00 2 8537 48 0 0
sp_qd_handle_on_cancel_clo 1913 1913 0 0.00 0 11795 308 0 0
sp_qd_handle_on_invoice_upd_sts 10463 10463 0 0.00 0 17926 521 0 0
sp_qd_handle_on_reserve_upd_sts 10378 10378 0 0.00 0 12505 57 0 0
sp_reserve_write_off 10378 10378 0 0.00 8 17890 278 0 0
sp_supplier_invoice 5774 5774 0 0.00 8 97254 3253 0 0
sp_supplier_order 5799 5799 0 0.00 6 28162 1374 0 0
srv_aggregate_perf_data 119 119 0 0.00 2544 180411 14459 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 689 181051 34475 0 0
srv_find_qd_qs_mism 1026635 1026635 0 0.00 0 14120 11 0 0
srv_make_invnt_saldo 525 525 0 0.00 48 129008 3545 0 0
srv_make_money_saldo 995 995 0 0.00 1 10188 320 0 0
srv_recalc_idx_stat 4 4 0 0.00 84651 556280 316615 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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 5225 5225 0 0.00 0 15471 899 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 6730 521 0 0
t$perf-norm:sp_cancel_client_order 1913 1913 0 0.00 0 1865 30 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 4685 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 4088 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 536 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 463 463 0 0.00 0 2403 36 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 80751 6882 0 0
t$perf-norm:sp_cancel_write_off 1105 1105 0 0.00 0 12301 33 0 0
t$perf-norm:sp_client_order 21781 21781 0 0.00 0 9966 38 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 1513 18 0 0
t$perf-norm:sp_pay_from_customer 7391 7391 0 0.00 0 14263 21 0 0
t$perf-norm:sp_pay_to_supplier 6101 6101 0 0.00 0 28550 24 0 0
t$perf-norm:sp_reserve_write_off 10378 10378 0 0.00 0 13949 25 0 0
t$perf-norm:sp_supplier_invoice 5774 5774 0 0.00 0 9626 37 0 0
t$perf-norm:sp_supplier_order 5799 5799 0 0.00 0 19092 37 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 604 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1179 20 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 2903 16 0 0
t$perf-norm:srv_make_money_saldo 995 995 0 0.00 0 4791 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 178 48 0 0
v_add_invoice_to_stock 10636 10388 248 2.33 0 6534 25 0 248
v_all_customers 39696 39696 0 0.00 0 130 4 0 0
v_all_suppliers 31916 31916 0 0.00 0 67 5 0 0
v_all_wares 43558 43558 0 0.00 0 141 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1397 19 0 0
v_cancel_client_order 3826 3826 0 0.00 0 3292 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 7277 20 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 3111 15 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 1665 13 0 0
v_cancel_supplier_invoice 952 926 26 2.73 0 462 23 0 26
v_cancel_supplier_order 834 834 0 0.00 0 968 12 0 0
v_cancel_write_off 2210 2210 0 0.00 0 2370 23 0 0
v_max_id_clo_ord 5798 5798 0 0.00 0 1173 5 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 3229 18 0 0
v_max_id_ord_sup 5773 5773 0 0.00 0 3996 8 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 199 7 0 0
v_max_non_paid_realizn 3939 3939 0 0.00 0 2413 8 0 0
v_min_id_clo_ord 5798 5798 0 0.00 0 535 7 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 10833 33 0 0
v_min_id_ord_sup 5773 5773 0 0.00 0 5615 10 0 0
v_min_non_paid_invoice 6101 6101 0 0.00 0 8508 17 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 7428 18 0 0
v_our_firm 10766 10766 0 0.00 0 57 5 0 0
v_reserve_write_off 20756 20756 0 0.00 0 3106 10 0 0
x_lock_depdocs_on_canc_invoice 10728 10728 0 0.00 0 20744 115 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3758 309 0 0
x_make_qstorno_1000_1200 5799 5799 0 0.00 2 28155 1206 0 0
x_make_qstorno_1200_2000 5774 5774 0 0.00 4 96822 3020 0 0
x_make_qstorno_2100_3300 477298 477298 0 0.00 0 137940 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2022 10:39:52 to 08-12-2022 10:39:56.

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:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:24 35888 8192000000 9740192432 10038341632 809803664 8.996 100 88 174 1 838791200 8361440 20626096
08:10:10 23921 8192000000 9781508928 10091134976 850140640 9.401 100 95 190 0 886892272 14217072 22534560
08:11:36 12646 8192000000 9818213024 10141343744 881315504 9.713 100 82 164 0 926265456 26062448 18887504
08:12:52 5466 8192000000 9869039440 10205491200 924170848 10.137 100 87 173 1 985677424 42312016 19194560
08:14:05 2637 8192000000 9924775136 10264174592 954336192 10.434 100 88 173 0 1039664000 67086496 18241312
08:15:11 2351 8192000000 9969256992 10322386944 987654144 10.759 100 87 168 1 1081543472 77337360 16551968
08:16:15 1545 8192000000 9990257792 10350231552 1017160000 11.045 100 95 187 2 1112138384 76539552 18438832
08:17:18 880 8192000000 10017856656 10384613376 1040692800 11.271 100 97 185 2 1140791616 81015312 19083504
08:18:20 1227 8192000000 10036842848 10412367872 1056335344 11.421 100 92 180 3 1155604784 81023824 18245616
08:19:22 1020 8192000000 10048805344 10422964224 1064911568 11.503 100 96 183 1 1170544032 86912272 18720192
08:20:25 6590 8192000000 10024380096 10403938304 1070678640 11.559 100 86 166 4 1150344160 62547584 17117936
08:21:32 1093 8192000000 10028287936 10409476096 1077682288 11.625 100 95 180 4 1147045360 51759856 17603216
08:22:35 2388 8192000000 10051833168 10441183232 1079683776 11.644 100 74 145 1 1172156272 78877664 13594832
08:23:38 1457 8192000000 10046490640 10433843200 1082801936 11.674 100 97 189 3 1174136672 72026544 19308192
08:24:40 1061 8192000000 10061449152 10448556032 1085755408 11.702 100 94 180 2 1179692784 74191600 19745776
08:25:42 1242 8192000000 10052347264 10441080832 1093346096 11.774 100 90 169 0 1177809184 68329872 16133216
08:26:45 4453 8192000000 10036760016 10433601536 1090287760 11.745 100 66 126 0 1165598000 60646096 14664144
08:27:50 988 8192000000 10026937680 10417299456 1086400496 11.708 100 82 155 1 1150833584 48296752 16136336
08:28:55 6482 8192000000 10020860720 10412953600 1069748768 11.550 99 59 114 1 1147150768 65292672 12109328
08:30:02 1062 8192000000 10017832576 10405871616 1076421504 11.613 100 82 156 1 1141931792 48803504 16706784
08:31:04 1146 8192000000 10031450256 10413953024 1076288624 11.612 100 94 187 0 1149350688 54295984 18766080
08:32:06 1271 8192000000 10014359952 10393980928 1055468832 11.413 100 96 181 0 1137959296 62549360 19941104
08:33:08 916 8192000000 10015563248 10395353088 1061285616 11.469 100 99 185 1 1136848656 55946624 19616416
08:34:11 1169 8192000000 9973259856 10345873408 1034653168 11.213 100 87 163 7 1096561280 44386096 17522016
08:35:14 4239 8192000000 10003353840 10383527936 1044475216 11.308 100 75 139 1 1130722672 71809136 14438320
08:36:19 1669 8192000000 9993953792 10381983744 1038958976 11.255 100 69 136 1 1119268736 66095904 14213856
08:37:22 1176 8192000000 9997764000 10381635584 1054123984 11.400 100 70 119 5 1120358384 53854544 12379856
08:38:34 6302 8192000000 10009155968 10394185728 1066344752 11.517 100 81 142 1 1133160848 52835664 13980432
08:39:49 13775 8192000000 10019451088 10412838912 1072390832 11.575 100 63 124 1 1134315312 50014112 11910368
08:41:20 3911 8192000000 10072728432 10465546240 1090259904 11.745 100 73 136 0 1194600848 89875952 14464992
08:42:28 3610 8192000000 10064709120 10461827072 1106444560 11.899 100 63 123 0 1177322816 57635456 13242800
08:43:36 2277 8192000000 10081389488 10476900352 1108398528 11.917 100 73 138 0 1203421456 80412832 14610096
08:44:43 11217 8192000000 10125818192 10536587264 1123699168 12.062 100 58 105 3 1240157408 103135456 13322784
08:46:01 1164 8192000000 10169916320 10564087808 1135611888 12.174 100 81 151 1 1282616144 130524576 16479680
08:47:08 2273 8192000000 10186488528 10587807744 1139007104 12.206 100 80 159 1 1300924896 144080880 17836912
08:48:16 1624 8192000000 10214110720 10628960256 1154701360 12.354 100 82 150 3 1334370880 163198880 16470640
08:49:23 1230 8192000000 10246687072 10649083904 1151018944 12.319 100 96 183 1 1363362576 191444848 20898784
08:50:28 1549 8192000000 10289126016 10696130560 1168791680 12.486 100 92 180 2 1404971200 214942448 21237072
08:51:32 1007 8192000000 10256377728 10698543104 1159370848 12.397 99 87 170 0 1368256960 188254384 20631728
08:52:36 1207 8192000000 10283693424 10684477440 1161898624 12.421 100 95 184 1 1394173168 211194384 21080160
08:53:40 1347 8192000000 10301411248 10705883136 1163438944 12.435 100 92 177 0 1410048128 226641552 19967632
08:54:43 937 8192000000 10346936624 10773082112 1178904400 12.580 100 94 184 3 1468585568 267854976 21826192
08:55:46 1376 8192000000 10352719856 10782912512 1176658368 12.559 100 86 170 0 1459733824 262826752 20248704
08:56:52 862 8192000000 10370735568 10783997952 1172836336 12.523 100 98 188 1 1474893584 280851872 21205376
08:57:57 794 8192000000 10396688960 10821410816 1185843360 12.645 100 95 187 0 1501209264 293547408 21818496
08:59:02 680 8192000000 10417667024 10839031808 1176090928 12.554 100 95 189 1 1522152880 323778592 22283360
09:00:04 750 8192000000 10449482112 10878021632 1172183008 12.517 100 92 181 1 1557143984 363394496 21566480
09:01:10 873 8192000000 10489111424 10921623552 1180775008 12.597 100 94 187 3 1593816944 390983552 22058384
09:02:13 1683 8192000000 10486866128 10927964160 1169311680 12.490 100 75 148 0 1589162048 401551616 18298752
09:03:18 2503 8192000000 10501619808 10941677568 1170514672 12.502 100 63 124 0 1607801584 421284208 16002704
09:04:27 3269 8192000000 10489463328 10931994624 1154076432 12.348 100 59 109 1 1590560800 422799280 13685088
09:05:33 5157 8192000000 10490883376 10929631232 1151674496 12.325 100 49 96 0 1595883840 431399696 12809648
09:06:49 4611 8192000000 10433923232 10944532480 1147212144 12.283 100 47 90 0 1533473936 373227104 13034688
09:07:56 15506 8192000000 10371390720 10884886528 1152572352 12.334 100 33 64 0 1463923584 302909456 8441776
09:09:20 29121 8192000000 10376056432 10839035904 1160325856 12.406 100 23 46 0 1467375520 301066560 5983104
09:11:05 41375 8192000000 10159083136 10677813248 1124117392 12.066 98 16 32 1 1306534432 177935632 4481408
09:13:03 138177 8192000000 10049002992 10502930432 1106630272 11.901 97 10 20 0 1166344704 57557712 2156720
09:16:22 80609 8192000000 10070341952 10542247936 1117768944 12.006 99 27 54 0 1176273152 52959504 5544704
09:19:14 71770 8192000000 10075107840 10518048768 1104043888 11.876 97 34 66 0 1171530112 59907792 7578432
09:21:30 181050 8192000000 10056316752 10509418496 1052270976 11.382 93 42 83 1 1109260336 47667808 9321552
09:25:33 3911 8192000000 10084343200 10518069248 1120420928 12.031 100 66 124 2 1172620240 39860512 12338800
09:26:42 9814 8192000000 10096193296 10520510464 1122041168 12.046 100 73 137 0 1189022272 52618112 14362992
09:27:54 21029 8192000000 10100988320 10530004992 1118919152 12.017 99 44 85 1 1182014304 54544704 8550448
09:29:20 74525 8192000000 10075270592 10537275392 1125654272 12.080 99 9 18 0 1171944784 44441168 1849344
09:32:24 20158 8192000000 10090662768 10564329472 1132177552 12.142 100 41 81 1 1185758432 45130272 8450608
09:33:45 70117 8192000000 10052527216 10520555520 1131370176 12.134 100 13 26 0 1172235200 37996912 2868112
09:35:56 32089 8192000000 10066410608 10528210944 1112486240 11.956 98 30 61 0 1161446000 42634672 6325088
09:37:55 49394 8192000000 10091790032 10544189440 1123221584 12.057 98 27 52 2 1176211120 47303216 5686320
09:39:46 11946 8192000000 10090019616 10525114368 1105433504 11.889 97 47 92 0 1186664896 71579920 9651472
09:41:01 15629 8192000000 10094327696 10536550400 1126103856 12.085 100 33 64 0 1196666480 62828176 7734448
09:42:18 11078 8192000000 10164784848 10599116800 1131546608 12.136 99 37 74 0 1253044352 112982880 8514864
09:43:32 8570 8192000000 10197767584 10638286848 1117756880 12.006 98 42 84 0 1291619552 164416064 9446608
09:44:42 20746 8192000000 10232622160 10667425792 1131837728 12.139 99 34 68 0 1314271696 174653792 7780176
09:46:19 20833 8192000000 10247206768 10713325568 1140116128 12.217 99 48 95 1 1356737088 206122496 10498464
09:47:42 49484 8192000000 10163475264 10610188288 1095807312 11.798 96 24 47 0 1239168368 137922288 5438768
09:49:32 52688 8192000000 10199012112 10664099840 1127616912 12.099 100 23 44 0 1309986736 176924720 5445104
09:51:25 115448 8192000000 10092308848 10576179200 1129802368 12.119 100 24 48 0 1194810768 60244416 4763984
09:54:36 44245 8192000000 10054230640 10517200896 1130156096 12.123 100 14 24 1 1150996176 17175632 3664448
09:56:37 70479 8192000000 10074189760 10542669824 1111680672 11.948 98 24 48 0 1158694832 41661632 5352528
09:59:05 24976 8192000000 10111956752 10547847168 1119105264 12.019 98 49 98 1 1200202560 70125776 10971520
10:00:30 76188 8192000000 10152160384 10607300608 1120740048 12.034 98 20 40 0 1219214320 94310848 4163424
10:02:57 43872 8192000000 10078692528 10523111424 1097058064 11.810 97 19 37 0 1141377776 41220576 3099136
10:04:43 33299 8192000000 10096210480 10536214528 1119805936 12.025 99 44 88 0 1187370976 59019376 8545664
10:06:21 37177 8192000000 10139965024 10593206272 1115199440 11.982 98 30 60 0 1215770016 94036608 6533968
10:08:02 76900 8192000000 10110258112 10596876288 1124987984 12.074 99 16 32 0 1170965632 43074688 2902960
10:10:33 74944 8192000000 10076941344 10542821376 1137769632 12.195 100 25 47 0 1169770240 27506880 4493728
10:12:48 92274 8192000000 10085894000 10562019328 1128942048 12.111 99 17 34 0 1196007040 63559472 3505520
10:16:52 65669 8192000000 10070942736 10517831680 1119839072 12.025 99 18 34 0 1154742352 31693312 3209968
10:19:01 23601 8192000000 10124861024 10568044544 1107718544 11.911 97 30 59 0 1180772544 67148096 5905904
10:20:27 109658 8192000000 10162423712 10606911488 1105098096 11.886 96 27 53 0 1220202336 109006704 6097536
10:23:21 110788 8192000000 10071106352 10513973248 1101292624 11.850 96 21 41 0 1176692176 71092048 4307504
10:26:13 24746 8192000000 10134150016 10577936384 1116828000 11.997 98 43 84 1 1201092304 75726384 8537920
10:27:45 67055 8192000000 10106348496 10551312384 1092443456 11.766 97 39 77 0 1172675504 72932672 7299376
10:30:00 87270 8192000000 10063963104 10507317248 1086485760 11.709 96 40 78 1 1138287600 43788608 8013232
10:32:45 42988 8192000000 10061882336 10531835904 1099803024 11.836 98 5 10 0 1150550864 49727648 1020192
10:34:35 45200 8192000000 10051372288 10534076416 1083795072 11.684 97 12 24 0 1127392352 40772656 2824624
10:36:20 73902 8192000000 9527908608 9947000832 644688032 7.295 57 6 11 0 723704576 77851136 1165408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-12-2022 10:39:58 to 08-12-2022 10:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 08-12-2022 10:40:15 to 08-12-2022 10:40:17.

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          227708
MON$OLDEST_ACTIVE               230939
MON$OLDEST_SNAPSHOT             230939
MON$NEXT_TRANSACTION            230939
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                       2088160
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             796
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 ---
:127: 
:128: mscs-test-161.budzdorov.ru    Thu Dec  8 10:37:39 2022
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
--- end of diff output ---

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

08.12.22 10:40:18. 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-08 10:40:21 No severe PSQL-related problems occured
08.12.22 10:40:22. End of report.