Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2021 13:41
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 17009712 kB
MemAvailable 30927432 kB
Buffers 0 kB
Cached 14769332 kB
SwapCached 3428 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.1
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 2021-10-24 13:37:14
DTS_END                         2021-10-24 13:37:14.9000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59160
TRN_ID                          212933
ATT_ID                          650
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: 7337.28 38416 880473
customer order: creation 142.32 407 17078
customer order: refuse 12.47 455 1496
order to supplier: creation 39.03 1669 4684
order to supplier: removal 2.77 192688 332
invoice (draft): creation 38.63 6056 4636
invoice (draft): removal 41.52 1619 4982
invoice accept: apply 34.54 42266 4145
invoice accept: cancel 37.06 1441 4447
customer reserve: creation 3062.62 442 367514
customer reserve: removal 3682.18 111 441862
realization accept: apply 71.18 297 8542
realization accept: cancel 65.23 196 7828
payment from customer: creation 50.06 170 6007
payment from customer: removal 4.78 148 573
payment to supplier: creation 41.73 179 5008
payment to supplier: removal 2.81 143 337
service: total inventory turnovers 2.06 14379 247
service: total monetary turnovers 5.62 1434 674
service: aggregate perf. data 0.64 27857 77
service: refresh index statistics 0.03 476370 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2021 13:42:09 to 24-10-2021 13:42:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9183
TEST_TIME 2 6412
TEST_TIME 3 6010
TEST_TIME 4 6074
TEST_TIME 5 6160
TEST_TIME 6 6879
TEST_TIME 7 6295
TEST_TIME 8 6112
TEST_TIME 9 6247
TEST_TIME 10 6412
TEST_TIME 11 6375
TEST_TIME 12 6732
TEST_TIME 13 6857
TEST_TIME 14 7022
TEST_TIME 15 7186
TEST_TIME 16 7374
TEST_TIME 17 7966
TEST_TIME 18 8178
TEST_TIME 19 8908
TEST_TIME 20 8743
TEST_TIME 21 8674
TEST_TIME 22 8986
TEST_TIME 23 9360
TEST_TIME 24 9427
TEST_TIME 25 9491
TEST_TIME 26 9673
TEST_TIME 27 9891
TEST_TIME 28 11610
TEST_TIME 29 11621
TEST_TIME 30 11412
TEST_TIME 31 11204
TEST_TIME 32 11197
TEST_TIME 33 11106
TEST_TIME 34 11028
TEST_TIME 35 10883
TEST_TIME 36 10629
TEST_TIME 37 10555
TEST_TIME 38 10714
TEST_TIME 39 10626
TEST_TIME 40 10583
TEST_TIME 41 10448
TEST_TIME 42 10345
TEST_TIME 43 10460
TEST_TIME 44 10400
TEST_TIME 45 10337
TEST_TIME 46 10239
TEST_TIME 47 10264
TEST_TIME 48 10226
TEST_TIME 49 10163
TEST_TIME 50 10126
TEST_TIME 51 10047
TEST_TIME 52 10081
TEST_TIME 53 9942
TEST_TIME 54 9818
TEST_TIME 55 9717
TEST_TIME 56 9611
TEST_TIME 57 9494
TEST_TIME 58 9387
TEST_TIME 59 9340
TEST_TIME 60 9213
TEST_TIME 61 9168
TEST_TIME 62 9106
TEST_TIME 63 9005
TEST_TIME 64 8890
TEST_TIME 65 8781
TEST_TIME 66 8704
TEST_TIME 67 8628
TEST_TIME 68 8569
TEST_TIME 69 8488
TEST_TIME 70 8378
TEST_TIME 71 8324
TEST_TIME 72 8280
TEST_TIME 73 8198
TEST_TIME 74 8104
TEST_TIME 75 8028
TEST_TIME 76 7961
TEST_TIME 77 7866
TEST_TIME 78 7811
TEST_TIME 79 7789
TEST_TIME 80 7736
TEST_TIME 81 7670
TEST_TIME 82 7588
TEST_TIME 83 7606
TEST_TIME 84 7533
TEST_TIME 85 7514
TEST_TIME 86 7471
TEST_TIME 87 7445
TEST_TIME 88 7429
TEST_TIME 89 7367
TEST_TIME 90 7312
TEST_TIME 91 7254
TEST_TIME 92 7210
TEST_TIME 93 7220
TEST_TIME 94 7225
TEST_TIME 95 7197
TEST_TIME 96 7171
TEST_TIME 97 7135
TEST_TIME 98 7122
TEST_TIME 99 7119
TEST_TIME 100 7161
TEST_TIME 101 7157
TEST_TIME 102 7174
TEST_TIME 103 7220
TEST_TIME 104 7203
TEST_TIME 105 7160
TEST_TIME 106 7148
TEST_TIME 107 7200
TEST_TIME 108 7240
TEST_TIME 109 7199
TEST_TIME 110 7230
TEST_TIME 111 7235
TEST_TIME 112 7235
TEST_TIME 113 7371
TEST_TIME 114 7358
TEST_TIME 115 7419
TEST_TIME 116 7381
TEST_TIME 117 7391
TEST_TIME 118 7380
TEST_TIME 119 7400
TEST_TIME 120 7341

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2021 13:42:12 to 24-10-2021 13:42:16.

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 1219948 1219948 0 0.00 0 25485 15 0 0
doc_list_biud 1219945 1219945 0 0.00 0 42862 51 0 0
sp_add_invoice_to_stock 4258 4145 113 2.65 45 680263 42266 0 113
sp_cancel_adding_invoice 4447 4447 0 0.00 7 77723 1441 0 0
sp_cancel_client_order 1496 1496 0 0.00 6 17427 455 0 0
sp_cancel_customer_reserve 441862 441862 0 0.00 1 27787 112 0 0
sp_cancel_pay_from_customer 573 573 0 0.00 3 6664 149 0 0
sp_cancel_pay_to_supplier 337 337 0 0.00 3 5259 143 0 0
sp_cancel_supplier_invoice 4987 4982 5 0.10 3 42875 1619 0 5
sp_cancel_supplier_order 332 332 0 0.00 20 1263160 192688 0 0
sp_cancel_write_off 7828 7828 0 0.00 1 11779 196 0 0
sp_client_order 17078 17078 0 0.00 4 50737 407 0 0
sp_customer_reserve 367514 367514 0 0.00 0 57832 442 0 0
sp_fill_shopping_cart 26391 26391 0 0.00 1 40191 69 0 0
sp_fill_shopping_cart_clo_res 367493 367493 0 0.00 0 55494 28 0 0
sp_get_clo_for_invoice 4124 4124 0 0.00 0 47924 515 0 0
sp_kill_cost_storno 13185 13185 0 0.00 0 11696 105 0 0
sp_kill_qstorno_ret_qs2qd 896866 896866 0 0.00 0 42859 60 0 0
sp_kill_qty_storno 473611 473611 0 0.00 0 42861 130 0 0
sp_lock_selected_doc 17407 17407 0 0.00 0 11390 7 0 0
sp_make_cost_storno 23681 23681 0 0.00 0 19407 87 0 0
sp_multiply_rows_for_pdistr 23678 23678 0 0.00 0 25479 54 0 0
sp_multiply_rows_for_qdistr 17078 17078 0 0.00 0 50727 178 0 0
sp_pay_from_customer 6007 6007 0 0.00 4 33737 171 0 0
sp_pay_to_supplier 5008 5008 0 0.00 5 12435 179 0 0
sp_payment_common 11014 11014 0 0.00 2 25131 37 0 0
sp_qd_handle_on_cancel_clo 1496 1496 0 0.00 1 17414 407 0 0
sp_qd_handle_on_invoice_upd_sts 8569 8569 0 0.00 0 24861 625 0 0
sp_qd_handle_on_reserve_upd_sts 8542 8542 0 0.00 0 12674 84 0 0
sp_reserve_write_off 8542 8542 0 0.00 7 21912 297 0 0
sp_supplier_invoice 4636 4636 0 0.00 9 152056 6057 0 0
sp_supplier_order 4684 4684 0 0.00 6 25710 1669 0 0
srv_aggregate_perf_data 77 77 0 0.00 2569 575187 27857 0 0
srv_fill_mon_cache_memory 77 77 0 0.00 950 131957 26462 0 0
srv_find_qd_qs_mism 840830 840830 0 0.00 0 11447 14 0 0
srv_make_invnt_saldo 247 247 0 0.00 122 184159 14380 0 0
srv_make_money_saldo 674 674 0 0.00 2 35560 1434 0 0
srv_recalc_idx_stat 4 4 0 0.00 189065 932836 476371 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4145 4145 0 0.00 0 8926 217 0 0
t$perf-norm:sp_cancel_adding_invoice 311 311 0 0.00 1 2575 109 0 0
t$perf-norm:sp_cancel_client_order 1496 1496 0 0.00 0 1522 15 0 0
t$perf-norm:sp_cancel_customer_reserve 541 541 0 0.00 0 975 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 573 573 0 0.00 0 1001 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 337 337 0 0.00 0 2074 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 325 325 0 0.00 0 1195 30 0 0
t$perf-norm:sp_cancel_supplier_order 332 332 0 0.00 0 26517 2178 0 0
t$perf-norm:sp_cancel_write_off 828 828 0 0.00 0 415 4 0 0
t$perf-norm:sp_client_order 17078 17078 0 0.00 0 6763 29 0 0
t$perf-norm:sp_customer_reserve 824 824 0 0.00 0 355 5 0 0
t$perf-norm:sp_pay_from_customer 6007 6007 0 0.00 0 3870 10 0 0
t$perf-norm:sp_pay_to_supplier 5008 5008 0 0.00 0 1332 8 0 0
t$perf-norm:sp_reserve_write_off 8542 8542 0 0.00 0 5082 12 0 0
t$perf-norm:sp_supplier_invoice 4636 4636 0 0.00 0 2637 25 0 0
t$perf-norm:sp_supplier_order 4684 4684 0 0.00 0 4939 30 0 0
t$perf-norm:srv_aggregate_perf_data 77 77 0 0.00 0 388 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 106 4 0 0
t$perf-norm:srv_make_invnt_saldo 247 247 0 0.00 0 3182 47 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 735 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 8 0 0
v_add_invoice_to_stock 8470 8244 226 2.66 0 13580 28 0 226
v_all_customers 30636 30636 0 0.00 0 137 1 0 0
v_all_suppliers 25688 25688 0 0.00 0 26 1 0 0
v_all_wares 34156 34156 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 622 622 0 0.00 0 11004 27 0 0
v_cancel_client_order 2992 2992 0 0.00 0 5553 11 0 0
v_cancel_customer_prepayment 1146 1146 0 0.00 0 1042 7 0 0
v_cancel_customer_reserve 1082 1082 0 0.00 0 5153 14 0 0
v_cancel_payment_to_supplier 674 674 0 0.00 0 3493 16 0 0
v_cancel_supplier_invoice 660 650 10 1.51 0 517 15 0 10
v_cancel_supplier_order 664 664 0 0.00 0 296 6 0 0
v_cancel_write_off 1656 1656 0 0.00 0 5218 22 0 0
v_max_id_clo_ord 4684 4684 0 0.00 0 3914 3 0 0
v_max_id_clo_res 824 824 0 0.00 0 9843 26 0 0
v_max_id_ord_sup 4627 4627 0 0.00 0 9481 6 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 353 3 0 0
v_max_non_paid_realizn 3418 3418 0 0.00 0 3429 4 0 0
v_min_id_clo_ord 4684 4684 0 0.00 0 12109 18 0 0
v_min_id_clo_res 824 824 0 0.00 0 9746 29 0 0
v_min_id_ord_sup 4627 4627 0 0.00 0 9472 13 0 0
v_min_non_paid_invoice 5008 5008 0 0.00 0 8605 19 0 0
v_min_non_paid_realizn 6006 6006 0 0.00 0 25127 36 0 0
v_our_firm 8696 8696 0 0.00 0 18 1 0 0
v_reserve_write_off 17084 17084 0 0.00 0 12228 11 0 0
x_lock_depdocs_on_canc_invoice 9104 9104 0 0.00 0 16942 222 0 0
x_lock_depdocs_on_canc_sup_ord 332 332 0 0.00 0 8561 635 0 0
x_make_qstorno_1000_1200 4684 4684 0 0.00 2 25678 1536 0 0
x_make_qstorno_1200_2000 4636 4636 0 0.00 4 152028 5782 0 0
x_make_qstorno_2100_3300 367514 367514 0 0.00 0 46628 409 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2021 13:42:17 to 24-10-2021 13:42:21.

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:18 1015 8192000000 8952120784 9013792768 7905792 0.096 4 2 4 0 8068000 90720 71488
11:08:21 21087 8192000000 9715203040 10011561984 793877600 8.834 100 86 172 0 822652784 7570928 21204256
11:09:43 8936 8192000000 9748040176 10051121152 817319616 9.071 100 94 188 0 852725856 12718768 22687472
11:10:57 17431 8192000000 9789656976 10102779904 847496784 9.375 100 86 172 1 886138832 18569168 20072880
11:12:21 13279 8192000000 9858508784 10186272768 898751200 9.886 100 86 171 0 960821344 42920048 19150096
11:13:38 8889 8192000000 9926957984 10269315072 945182944 10.344 100 78 155 0 1024435504 62658256 16594304
11:14:49 4451 8192000000 9948720992 10301595648 973891760 10.625 100 79 158 2 1059587552 69586800 16108992
11:15:55 1837 8192000000 9991297072 10351054848 1002834240 10.906 100 86 171 2 1102532752 82784288 16914224
11:17:00 1758 8192000000 10012289888 10378776576 1025785712 11.128 100 88 177 1 1112107488 68033232 18288544
11:18:04 2231 8192000000 10026783280 10395193344 1044270688 11.306 100 96 191 0 1120051152 56378576 19401888
11:19:08 11134 8192000000 9988704400 10374430720 1048326896 11.345 100 42 80 2 1110549744 54122752 8100096
11:20:20 4647 8192000000 9992154992 10376441856 1059367008 11.450 100 57 114 0 1092522176 20588784 12566384
11:21:26 8666 8192000000 9998774400 10401054720 1070083232 11.553 100 29 58 1 1101100816 24950608 6066976
11:22:35 35324 8192000000 10015368160 10403692544 1073722432 11.588 100 62 124 3 1106018928 18574528 13721968
11:24:14 6866 8192000000 10023174064 10428608512 1078675056 11.635 100 44 84 1 1109481920 21809408 8997456
11:25:23 6622 8192000000 10021609088 10425237504 1081826336 11.665 100 44 86 0 1110915392 19715104 9373952
11:26:31 8550 8192000000 9991455440 10398490624 1073376848 11.584 100 19 36 2 1100106576 22337760 4391968
11:27:40 47708 8192000000 9991490288 10394578944 1037840752 11.244 96 36 72 1 1058906432 13884144 7181536
11:29:28 4806 8192000000 9965026848 10350751744 1014074688 11.015 97 56 108 1 1050928768 25571104 11282976
11:30:36 6823 8192000000 9950060272 10331607040 1012320000 10.998 97 44 89 2 1030463824 7944720 10199104
11:31:43 12402 8192000000 9930259472 10315223040 983115072 10.715 96 17 32 2 1010146976 23436432 3595472
11:32:58 22795 8192000000 9940079872 10345000960 1031598080 11.184 99 40 79 4 1060667808 20203616 8866112
11:34:21 6063 8192000000 9975792288 10356711424 1047366336 11.335 100 65 128 2 1069647600 8905664 13375600
11:35:32 8001 8192000000 10001621696 10395217920 1060864240 11.465 100 46 91 3 1085000992 14467584 9669168
11:36:50 61466 8192000000 10012378256 10404290560 1078723392 11.635 100 34 68 1 1107266128 21927024 6615712
11:38:57 26263 8192000000 10053963440 10456829952 1107564176 11.909 100 40 80 0 1163986048 48060352 8361520
11:40:38 14999 8192000000 10092768544 10496360448 1121348624 12.040 100 49 97 0 1207158720 75811600 9998496
11:41:57 8947 8192000000 10135427920 10537795584 1140220320 12.218 100 48 95 1 1243176528 92530272 10425936
11:43:08 26726 8192000000 10182224896 10601275392 1142136608 12.236 99 46 92 0 1268839280 116401888 10300784
11:44:59 3961 8192000000 10220913696 10629455872 1164064176 12.441 100 58 115 0 1325419520 148956656 12398688
11:46:15 10925 8192000000 10261471664 10670215168 1169413712 12.491 100 58 116 2 1363358832 181287376 12657744
11:47:29 2883 8192000000 10267296448 10671448064 1169064896 12.488 100 74 145 1 1368097328 182945856 16086576
11:48:37 3336 8192000000 10316118608 10722209792 1170587712 12.502 99 78 155 0 1408926832 221132944 17206176
11:49:43 2473 8192000000 10334715536 10740224000 1160479840 12.408 99 77 154 3 1432679568 254362272 17837456
11:50:49 3243 8192000000 10344160112 10749091840 1165315696 12.453 100 76 148 1 1441032864 259479856 16237312
11:51:59 1038 8192000000 10363944272 10762878976 1144202384 12.255 100 86 174 1 1462772736 298862752 19707600
11:53:05 2641 8192000000 10415169232 10810621952 1152378560 12.332 100 88 178 1 1513404704 340901840 20124304
11:54:11 1494 8192000000 10419190544 10858975232 1162859760 12.430 100 91 178 0 1532346928 347239104 22248064
11:55:17 3890 8192000000 10451081104 10859573248 1159807088 12.401 100 67 134 1 1541636528 366194880 15634560
11:56:25 949 8192000000 10445044496 10838806528 1147295856 12.284 100 95 190 1 1533967568 364385248 22286464
11:57:29 6176 8192000000 10389513664 10810892288 1118861248 12.016 99 45 88 0 1484443680 354409232 11173200
11:58:46 1468 8192000000 10488257808 10901168128 1142756128 12.241 100 59 117 3 1584181456 426773488 14651840
11:59:57 4232 8192000000 10450019984 10886029312 1155446704 12.361 100 54 108 0 1539717600 371107776 13163120
12:01:03 13805 8192000000 10411048032 10876088320 1150602400 12.315 100 37 74 1 1508815456 348600720 9612336
12:02:22 12288 8192000000 10454182064 10886176768 1148974304 12.300 100 53 105 0 1546278640 384600944 12703392
12:03:39 23030 8192000000 10393141568 10893238272 1120939264 12.036 100 24 48 1 1516600976 388659280 7002432
12:05:17 27858 8192000000 10099787968 10617966592 1116804208 11.997 100 17 34 0 1332086160 210474336 4807616
12:06:56 64640 8192000000 10055113232 10478411776 1069750464 11.550 95 17 35 1 1091268512 18308720 3209328
12:09:24 59928 8192000000 10068354688 10507214848 1079285168 11.641 96 16 32 0 1113638992 31124240 3229584
12:11:36 67092 8192000000 10112023248 10546102272 1121109232 12.037 99 24 48 3 1179384544 53432704 4842608
12:14:31 35953 8192000000 10143096512 10558865408 1105849776 11.893 98 25 50 1 1244116272 132257792 6008704
12:16:08 12983 8192000000 10080598368 10510024704 1134931616 12.168 100 34 68 1 1182733472 41100448 6701408
12:17:46 61294 8192000000 10112109936 10557063168 1137432544 12.191 100 20 40 1 1209769936 67901104 4436288
12:19:49 3741 8192000000 10153731008 10570371072 1128046496 12.103 99 69 134 0 1240557952 97189872 15321584
12:20:53 30572 8192000000 10096212048 10609537024 1133272912 12.152 100 17 35 1 1226159904 88060144 4826848
12:22:28 43805 8192000000 10100114512 10577027072 1126894048 12.092 99 13 26 0 1203384480 73441264 3049168
12:24:12 38556 8192000000 10120201056 10592083968 1132568032 12.146 100 23 46 1 1229434144 91571296 5294816
12:25:59 31432 8192000000 10094478960 10549055488 1137133360 12.189 100 9 16 0 1201731344 63136048 1461936
12:27:30 43928 8192000000 10106596160 10545532928 1131913024 12.139 99 20 40 1 1191451232 55985120 3553088
12:29:18 32047 8192000000 10089728768 10544476160 1131744224 12.138 99 39 78 0 1185640240 45971264 7924752
12:30:53 9213 8192000000 10098784096 10516029440 1131331600 12.134 100 55 110 0 1194643456 51935520 11376336
12:32:09 25706 8192000000 10121754720 10550124544 1128325616 12.106 99 46 92 0 1216037520 77875664 9836240
12:33:52 33131 8192000000 10141920928 10571296768 1134534880 12.164 100 47 94 0 1230210768 85828672 9847216
12:36:08 49561 8192000000 10096072816 10543525888 1101955104 11.856 97 24 46 2 1189885760 82853616 5077040
12:38:02 27987 8192000000 10100128064 10541604864 1120460560 12.031 100 31 62 0 1202965392 75814880 6689952
12:39:40 37577 8192000000 10066215984 10485526528 1109138912 11.924 99 36 72 1 1150070016 33916032 7015072
12:41:35 12321 8192000000 10101277360 10517544960 1132743136 12.147 100 48 96 0 1204790624 62295280 9752208
12:42:49 23243 8192000000 10131082256 10550710272 1139066544 12.207 100 40 80 0 1222698368 75456432 8175392
12:44:17 30033 8192000000 10139774288 10570190848 1141336400 12.228 100 48 96 1 1240048832 89096416 9616016
12:45:50 48831 8192000000 10131598208 10564792320 1137552288 12.192 100 42 84 1 1234378128 88322624 8503216
12:47:42 17365 8192000000 10115693680 10557923328 1141478192 12.229 100 39 78 0 1228178832 79139312 7561328
12:49:26 67598 8192000000 10122536384 10544635904 1143138928 12.245 100 48 96 1 1220485344 67671184 9675232
12:51:35 55923 8192000000 10118897072 10537103360 1131915632 12.139 100 40 80 0 1209332736 69346304 8070800
12:54:06 57020 8192000000 10145280080 10558541824 1109424816 11.927 98 62 123 0 1199148864 76770944 12953104
12:56:11 21001 8192000000 10127645552 10581323776 1126258432 12.086 100 39 78 1 1230486000 96315376 7912192
12:57:41 131957 8192000000 10125632528 10558115840 1126036320 12.084 99 47 94 0 1205523488 68529520 10957648
13:01:17 45553 8192000000 10127840640 10559082496 1146300400 12.275 100 37 74 0 1232906192 78977456 7628336
13:03:07 56796 8192000000 10136884480 10587410432 1136700672 12.184 99 43 86 1 1234567392 89162016 8704704
13:05:11 40237 8192000000 10096734336 10515775488 1119554016 12.023 98 39 78 2 1179618896 52272640 7792240
13:06:55 21906 8192000000 10127450752 10537279488 1117120224 12.000 99 61 121 0 1205295840 75396592 12779024
13:08:20 15156 8192000000 10181947456 10590810112 1142343776 12.238 100 76 152 1 1265101104 107126208 15631120
13:09:36 24095 8192000000 10201618000 10614435840 1138770576 12.204 100 68 134 2 1289240224 136278656 14190992
13:11:01 11929 8192000000 10166193184 10581069824 1128720464 12.109 100 77 152 0 1255281120 111230912 15329744
13:12:14 15651 8192000000 10151152512 10555121664 1122606080 12.052 100 72 144 1 1239733728 102583712 14543936
13:13:34 18625 8192000000 10185717136 10596233216 1134617536 12.165 100 77 153 0 1277891040 127458256 15815248
13:14:58 9422 8192000000 10170146848 10574360576 1095656464 11.796 97 72 144 0 1244008208 133664192 14687552
13:16:19 8108 8192000000 10189306896 10606841856 1129705040 12.119 100 64 126 1 1285962000 142321248 13935712
13:17:33 2121 8192000000 10180799280 10581266432 1139054400 12.207 100 83 165 0 1265586144 108851664 17680080
13:18:42 3770 8192000000 10212663232 10626592768 1147716576 12.288 100 72 142 0 1305514768 142126528 15671664
13:19:49 11934 8192000000 10224802272 10650275840 1147237440 12.284 100 47 93 1 1323392640 166041200 10114000
13:21:07 26984 8192000000 10216266000 10636496896 1153605152 12.343 99 48 96 0 1297684656 133357072 10722432
13:22:36 32269 8192000000 10232950752 10667245568 1157180000 12.377 100 25 50 0 1331349024 168118640 6050384
13:24:12 11679 8192000000 10263196000 10706092032 1151823248 12.327 100 57 114 2 1362782448 198670640 12288560
13:25:33 6049 8192000000 10193638864 10644799488 1143077280 12.244 100 47 93 0 1307292832 153549840 10665712
13:26:40 4995 8192000000 10216029200 10630397952 1131360624 12.134 100 51 102 1 1308910400 166617104 10932672
13:27:51 24476 8192000000 10233636000 10673790976 1149366560 12.304 100 49 98 0 1333853392 173870480 10616352
13:29:23 3651 8192000000 10254213008 10663858176 1149630432 12.306 100 82 163 0 1343445376 176348368 17466576
13:30:28 16528 8192000000 10244949104 10707910656 1143711968 12.250 100 54 108 2 1354741728 199484656 11545104
13:31:47 45824 8192000000 10123772592 10612006912 1121286384 12.039 98 26 52 0 1243076304 116231904 5558016
13:33:48 58604 8192000000 10212102096 10657972224 1127615392 12.099 98 31 62 2 1283432032 148648336 7168304
13:36:09 82573 8192000000 9400106416 9782329344 500320832 5.755 43 11 22 0 610107552 106972896 2813824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-10-2021 13:42:23 to 24-10-2021 13:42:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2021 13:42:40 to 24-10-2021 13:42:42.

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          210149
MON$OLDEST_ACTIVE               214419
MON$OLDEST_SNAPSHOT             214419
MON$NEXT_TRANSACTION            214419
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                       2111296
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 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 ---
:306: 
:307: mscs-test-161.budzdorov.ru    Sun Oct 24 13:37:35 2021
:308:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:309: 
--- end of diff output ---

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

24.10.21 13:42:43. 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
2021-10-24 13:42:45 No severe PSQL-related problems occured
24.10.21 13:42:47. End of report.