Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2022 04: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 15458088 kB
MemAvailable 31258396 kB
Buffers 20 kB
Cached 16207240 kB
SwapCached 2444 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.2
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-06-17 04:37:17
DTS_END                         2022-06-17 04:37:17.1620
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/49290
TRN_ID                          219969
ATT_ID                          624
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: 9056.11 31555 1086733
customer order: creation 165.11 482 19813
customer order: refuse 14.68 452 1761
order to supplier: creation 45.35 1430 5442
order to supplier: removal 3.23 199390 387
invoice (draft): creation 46.07 3198 5528
invoice (draft): removal 50.76 1072 6091
invoice accept: apply 41.85 27742 5022
invoice accept: cancel 46.03 1419 5524
customer reserve: creation 3833.09 265 459971
customer reserve: removal 4515.08 113 541809
realization accept: apply 80.62 311 9674
realization accept: cancel 86.75 174 10410
payment from customer: creation 57.93 195 6951
payment from customer: removal 5.84 141 701
payment to supplier: creation 48.16 216 5779
payment to supplier: removal 3.33 130 400
service: total inventory turnovers 3.81 4948 457
service: total monetary turnovers 7.65 447 918
service: aggregate perf. data 0.75 21776 90
service: refresh index statistics 0.04 367208 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2022 04:39:58 to 17-06-2022 04:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6007
TEST_TIME 2 6325
TEST_TIME 3 5834
TEST_TIME 4 6840
TEST_TIME 5 6469
TEST_TIME 6 6104
TEST_TIME 7 6478
TEST_TIME 8 6530
TEST_TIME 9 6714
TEST_TIME 10 6621
TEST_TIME 11 6563
TEST_TIME 12 6618
TEST_TIME 13 6647
TEST_TIME 14 6673
TEST_TIME 15 6684
TEST_TIME 16 7103
TEST_TIME 17 7206
TEST_TIME 18 7321
TEST_TIME 19 7543
TEST_TIME 20 7636
TEST_TIME 21 7841
TEST_TIME 22 7772
TEST_TIME 23 7825
TEST_TIME 24 8088
TEST_TIME 25 8107
TEST_TIME 26 8381
TEST_TIME 27 8475
TEST_TIME 28 8532
TEST_TIME 29 8718
TEST_TIME 30 9037
TEST_TIME 31 9548
TEST_TIME 32 9487
TEST_TIME 33 9877
TEST_TIME 34 9964
TEST_TIME 35 9855
TEST_TIME 36 10171
TEST_TIME 37 10143
TEST_TIME 38 10195
TEST_TIME 39 10422
TEST_TIME 40 10185
TEST_TIME 41 10149
TEST_TIME 42 10245
TEST_TIME 43 10437
TEST_TIME 44 10674
TEST_TIME 45 10649
TEST_TIME 46 10563
TEST_TIME 47 10449
TEST_TIME 48 10317
TEST_TIME 49 10268
TEST_TIME 50 10365
TEST_TIME 51 10306
TEST_TIME 52 10222
TEST_TIME 53 10166
TEST_TIME 54 10196
TEST_TIME 55 10066
TEST_TIME 56 9997
TEST_TIME 57 9912
TEST_TIME 58 9886
TEST_TIME 59 9804
TEST_TIME 60 9732
TEST_TIME 61 9648
TEST_TIME 62 9537
TEST_TIME 63 9532
TEST_TIME 64 9502
TEST_TIME 65 9420
TEST_TIME 66 9484
TEST_TIME 67 9478
TEST_TIME 68 9472
TEST_TIME 69 9391
TEST_TIME 70 9419
TEST_TIME 71 9484
TEST_TIME 72 9464
TEST_TIME 73 9398
TEST_TIME 74 9419
TEST_TIME 75 9424
TEST_TIME 76 9413
TEST_TIME 77 9384
TEST_TIME 78 9388
TEST_TIME 79 9470
TEST_TIME 80 9443
TEST_TIME 81 9418
TEST_TIME 82 9477
TEST_TIME 83 9568
TEST_TIME 84 9546
TEST_TIME 85 9498
TEST_TIME 86 9546
TEST_TIME 87 9532
TEST_TIME 88 9480
TEST_TIME 89 9411
TEST_TIME 90 9403
TEST_TIME 91 9344
TEST_TIME 92 9309
TEST_TIME 93 9274
TEST_TIME 94 9234
TEST_TIME 95 9253
TEST_TIME 96 9187
TEST_TIME 97 9184
TEST_TIME 98 9187
TEST_TIME 99 9212
TEST_TIME 100 9214
TEST_TIME 101 9249
TEST_TIME 102 9247
TEST_TIME 103 9189
TEST_TIME 104 9255
TEST_TIME 105 9206
TEST_TIME 106 9231
TEST_TIME 107 9279
TEST_TIME 108 9373
TEST_TIME 109 9395
TEST_TIME 110 9346
TEST_TIME 111 9389
TEST_TIME 112 9356
TEST_TIME 113 9307
TEST_TIME 114 9261
TEST_TIME 115 9239
TEST_TIME 116 9250
TEST_TIME 117 9206
TEST_TIME 118 9171
TEST_TIME 119 9144
TEST_TIME 120 9077

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-06-2022 04:40:02 to 17-06-2022 04:40:05.

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 1509727 1509727 0 0.00 0 22046 15 0 0
doc_list_biud 1509719 1509719 0 0.00 0 24745 47 0 0
sp_add_invoice_to_stock 5111 5022 89 1.74 51 193776 27742 0 89
sp_cancel_adding_invoice 5524 5524 0 0.00 6 67517 1420 0 0
sp_cancel_client_order 1761 1761 0 0.00 6 6087 452 0 0
sp_cancel_customer_reserve 541809 541809 0 0.00 1 24753 114 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 4854 141 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 4854 130 0 0
sp_cancel_supplier_invoice 6099 6091 8 0.13 3 15337 1072 0 8
sp_cancel_supplier_order 387 387 0 0.00 24 1511603 199390 0 0
sp_cancel_write_off 10410 10410 0 0.00 2 9704 175 0 0
sp_client_order 19813 19813 0 0.00 6 19633 483 0 0
sp_customer_reserve 459971 459971 0 0.00 1 85277 265 0 0
sp_fill_shopping_cart 30775 30775 0 0.00 2 16434 89 0 0
sp_fill_shopping_cart_clo_res 459953 459953 0 0.00 0 15256 18 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 36876 272 0 0
sp_kill_cost_storno 17035 17035 0 0.00 0 9681 81 0 0
sp_kill_qstorno_ret_qs2qd 1100506 1100506 0 0.00 0 24739 49 0 0
sp_kill_qty_storno 580629 580629 0 0.00 0 24744 112 0 0
sp_lock_selected_doc 20462 20462 0 0.00 0 7159 8 0 0
sp_make_cost_storno 27379 27379 0 0.00 0 16692 98 0 0
sp_multiply_rows_for_pdistr 27377 27377 0 0.00 0 7196 41 0 0
sp_multiply_rows_for_qdistr 19813 19813 0 0.00 0 11439 168 0 0
sp_pay_from_customer 6951 6951 0 0.00 5 11769 195 0 0
sp_pay_to_supplier 5779 5779 0 0.00 7 14281 217 0 0
sp_payment_common 12730 12730 0 0.00 2 7954 54 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 6067 376 0 0
sp_qd_handle_on_invoice_upd_sts 10497 10497 0 0.00 0 23286 594 0 0
sp_qd_handle_on_reserve_upd_sts 9674 9674 0 0.00 0 18868 71 0 0
sp_reserve_write_off 9674 9674 0 0.00 9 26905 311 0 0
sp_supplier_invoice 5528 5528 0 0.00 10 83921 3198 0 0
sp_supplier_order 5442 5442 0 0.00 7 42866 1430 0 0
srv_aggregate_perf_data 90 90 0 0.00 2873 249543 21777 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 806 126737 29967 0 0
srv_find_qd_qs_mism 1038270 1038270 0 0.00 0 22044 11 0 0
srv_make_invnt_saldo 457 457 0 0.00 33 105834 4949 0 0
srv_make_money_saldo 918 918 0 0.00 1 27843 448 0 0
srv_recalc_idx_stat 5 5 0 0.00 157261 498676 367208 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5024 5024 0 0.00 1 24261 1051 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 26684 671 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 11674 35 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 4386 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 731 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 5909 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1479 41 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 130309 8283 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1461 19 0 0
t$perf-norm:sp_client_order 19813 19813 0 0.00 0 11919 41 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 454 16 0 0
t$perf-norm:sp_pay_from_customer 6951 6951 0 0.00 0 5882 20 0 0
t$perf-norm:sp_pay_to_supplier 5779 5779 0 0.00 0 7686 22 0 0
t$perf-norm:sp_reserve_write_off 9674 9674 0 0.00 0 3734 24 0 0
t$perf-norm:sp_supplier_invoice 5528 5528 0 0.00 0 13862 37 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 5206 36 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 381 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 362 16 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 665 21 0 0
t$perf-norm:srv_make_money_saldo 918 918 0 0.00 0 389 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 187 59 0 0
v_add_invoice_to_stock 10124 9946 178 1.75 0 16938 29 0 178
v_all_customers 36604 36604 0 0.00 0 70 4 0 0
v_all_suppliers 30280 30280 0 0.00 0 57 5 0 0
v_all_wares 39626 39626 0 0.00 0 183 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 88 14 0 0
v_cancel_client_order 3522 3522 0 0.00 0 1621 10 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 4812 16 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 903 12 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 248 10 0 0
v_cancel_supplier_invoice 856 840 16 1.86 0 342 21 0 16
v_cancel_supplier_order 774 774 0 0.00 0 1406 14 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2641 24 0 0
v_max_id_clo_ord 5442 5442 0 0.00 0 1442 6 0 0
v_max_id_clo_res 993 993 0 0.00 0 3818 18 0 0
v_max_id_ord_sup 5520 5520 0 0.00 0 6889 8 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 3225 10 0 0
v_max_non_paid_realizn 3517 3517 0 0.00 0 1657 8 0 0
v_min_id_clo_ord 5442 5442 0 0.00 0 6929 11 0 0
v_min_id_clo_res 993 993 0 0.00 0 1761 10 0 0
v_min_id_ord_sup 5520 5520 0 0.00 0 5958 8 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 7943 22 0 0
v_min_non_paid_realizn 6951 6951 0 0.00 0 6683 21 0 0
v_our_firm 9890 9890 0 0.00 0 59 5 0 0
v_reserve_write_off 19348 19348 0 0.00 0 6040 11 0 0
x_lock_depdocs_on_canc_invoice 11195 11195 0 0.00 0 25641 143 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 16052 450 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 3 42831 1225 0 0
x_make_qstorno_1200_2000 5528 5528 0 0.00 4 71903 2982 0 0
x_make_qstorno_2100_3300 459971 459971 0 0.00 0 85272 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2022 04:40:06 to 17-06-2022 04:40:10.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 28015 8192000000 9714706160 10009436160 788234512 8.777 100 89 178 0 818245408 7523904 22486992
02:09:57 19127 8192000000 9757515584 10064875520 834957040 9.249 100 89 178 2 868448272 12076992 21414240
02:11:20 15125 8192000000 9791495088 10106441728 860131088 9.501 100 87 174 0 899545856 19394896 20019872
02:12:49 10944 8192000000 9869720848 10203521024 919702544 10.093 100 82 164 1 979201680 41652256 17846880
02:14:04 6426 8192000000 9930762896 10274820096 957392688 10.464 100 85 164 0 1050806336 76458128 16955520
02:15:13 4055 8192000000 9958108480 10308616192 994874960 10.829 100 89 177 0 1069204160 55731840 18597360
02:16:19 1352 8192000000 10001250288 10363437056 1024433952 11.115 100 89 171 0 1120354576 79599824 16320800
02:17:22 1561 8192000000 10024260576 10393681920 1039171952 11.257 100 89 173 0 1146547632 90087712 17287968
02:18:26 1021 8192000000 10020255056 10396106752 1055649616 11.415 100 97 189 0 1143785104 69421424 18714064
02:19:28 997 8192000000 10041937744 10419412992 1073048224 11.581 100 94 183 1 1165331200 73466704 18816272
02:20:31 9589 8192000000 10040416080 10417434624 1074224560 11.592 100 89 160 2 1156224720 66191584 15808576
02:21:41 1090 8192000000 10032735456 10416885760 1079373872 11.642 100 95 182 1 1159605280 61333952 18897456
02:22:44 2325 8192000000 10039164768 10429612032 1082956272 11.676 100 79 152 2 1164205648 65931888 15317488
02:23:49 1392 8192000000 10043265824 10431262720 1087530976 11.719 100 87 165 2 1166237120 61103904 17602240
02:24:51 1277 8192000000 10073254928 10460086272 1086748480 11.712 100 96 191 0 1193900720 87451680 19700560
02:25:53 1285 8192000000 10060465488 10449346560 1095033424 11.790 100 95 182 3 1177214400 63566960 18614016
02:26:55 1111 8192000000 10058219136 10445737984 1090725040 11.750 100 97 193 1 1178367616 67892064 19750512
02:27:58 1256 8192000000 10046426896 10434588672 1087021936 11.714 100 99 184 0 1168466656 61005888 20438832
02:29:00 1139 8192000000 10039008288 10424037376 1086997184 11.714 100 94 174 1 1153949280 48940752 18011344
02:30:02 1441 8192000000 10043490640 10429669376 1075660800 11.606 99 89 172 2 1163278656 70263040 17354816
02:31:05 1229 8192000000 10059825536 10448723968 1088911440 11.732 100 95 185 3 1180552384 72258736 19382208
02:32:07 1086 8192000000 10040422928 10420375552 1056992080 11.428 100 96 180 1 1162142784 86847264 18303440
02:33:09 1517 8192000000 10020541568 10395557888 1034534880 11.212 100 94 174 1 1139851200 86949440 18366880
02:34:12 1248 8192000000 10006427632 10385244160 1054125792 11.400 100 93 176 3 1131279056 58729568 18423696
02:35:14 1332 8192000000 9982858384 10364096512 1043600688 11.299 99 86 158 5 1106354768 44491168 18262912
02:36:16 980 8192000000 10005437728 10383921152 1052649520 11.386 100 84 163 1 1127594416 58113952 16830944
02:37:18 4597 8192000000 9982536032 10360918016 1044244720 11.305 99 75 144 1 1100340272 42248096 13847456
02:38:31 1985 8192000000 10028162144 10408869888 1061957648 11.475 100 78 148 1 1146141408 69016400 15167360
02:39:36 9450 8192000000 10044617680 10431766528 1068517616 11.538 100 73 140 2 1162023984 79558576 13947792
02:40:49 1583 8192000000 10077278512 10456133632 1082272704 11.669 100 91 170 3 1191994016 92546864 17174448
02:41:56 2906 8192000000 10080449616 10463248384 1099259536 11.831 100 87 164 0 1195810688 80543664 16007488
02:43:05 1566 8192000000 10112166096 10499481600 1097605344 11.815 99 80 150 0 1218837056 105392000 15839712
02:44:09 1645 8192000000 10138874448 10538209280 1114542080 11.975 100 85 164 0 1261270560 128298576 18429904
02:45:16 1383 8192000000 10147671840 10539933696 1122545856 12.051 100 85 162 0 1259243232 120252704 16444672
02:46:23 876 8192000000 10195190352 10584072192 1132032224 12.141 100 95 178 1 1304873072 154054912 18785936
02:47:25 6839 8192000000 10204463376 10609770496 1146879504 12.280 100 70 128 2 1314975584 154301616 13794464
02:48:34 2431 8192000000 10274540000 10677325824 1161576800 12.418 100 83 166 1 1389941312 209995520 18368992
02:49:39 5332 8192000000 10306366048 10703990784 1166782848 12.467 100 80 157 2 1408163664 223624048 17756768
02:50:47 2033 8192000000 10333144144 10739400704 1173904512 12.533 100 89 168 1 1449575248 256125184 19545552
02:51:54 1066 8192000000 10361036736 10757124096 1166053168 12.460 99 91 179 0 1468656944 282821904 19781872
02:53:00 1488 8192000000 10364542112 10799943680 1176896576 12.561 100 98 190 1 1472782384 274015536 21870272
02:54:04 1140 8192000000 10378555888 10804617216 1185797264 12.644 100 83 161 0 1487232544 282873136 18562144
02:55:08 988 8192000000 10440096176 10846642176 1192614768 12.708 100 96 190 0 1545874160 331222464 22036928
02:56:16 890 8192000000 10454346464 10896384000 1198255008 12.760 100 96 188 0 1571786912 351144368 22387536
02:57:20 1060 8192000000 10428958288 10860810240 1187952672 12.664 100 96 188 2 1537118464 324675712 24490080
02:58:28 1311 8192000000 10464523328 10887061504 1187341488 12.659 100 92 182 2 1570750752 362063152 21346112
02:59:33 865 8192000000 10513498016 10932809728 1197314000 12.751 100 94 186 0 1613291280 393293200 22684080
03:00:39 799 8192000000 10530720416 10989989888 1211480048 12.883 100 98 195 0 1634052544 398382256 24190240
03:01:43 1027 8192000000 10546091504 11005542400 1198059600 12.758 100 94 184 0 1648908096 426968720 23879776
03:02:46 5080 8192000000 10540327536 11001544704 1189488880 12.679 100 72 143 0 1645644352 437149664 19005808
03:03:55 8341 8192000000 10524498016 10970349568 1181544352 12.605 100 51 100 2 1642605136 448092736 12968048
03:05:21 9077 8192000000 10531807728 10991636480 1176406464 12.557 100 47 94 1 1632842496 444170912 12265120
03:06:44 10906 8192000000 10471908016 10939703296 1144053504 12.254 99 46 89 0 1586435632 430550208 11831920
03:08:03 8850 8192000000 10389734912 10874810368 1150152368 12.311 100 37 72 2 1492965264 333509952 9302944
03:09:14 4475 8192000000 10341536720 10779074560 1156600704 12.371 100 72 136 2 1438129984 266682960 14846320
03:10:24 11421 8192000000 10285691728 10741424128 1161418448 12.417 100 28 56 0 1393219648 224902336 6898864
03:11:41 14321 8192000000 10313428176 10753806336 1150263728 12.312 99 37 71 1 1407241168 248765200 8212240
03:12:58 6616 8192000000 10300145920 10776731648 1148951712 12.300 100 50 98 0 1395129264 234208496 11969056
03:14:05 12711 8192000000 10267646192 10724253696 1148914848 12.299 100 45 87 1 1353842912 195552432 9375632
03:15:21 22855 8192000000 10274257392 10740965376 1139822016 12.214 99 39 76 0 1369815488 221124416 8869056
03:16:45 48801 8192000000 10301427456 10747588608 1162918496 12.431 100 27 54 0 1416342272 246372896 7050880
03:18:47 23322 8192000000 10333863024 10774130688 1158890592 12.393 100 51 87 4 1430187584 260850896 10446096
03:20:11 38915 8192000000 10197573616 10656636928 1139597312 12.212 100 21 41 1 1346206720 201497088 5112320
03:21:51 53510 8192000000 10082800464 10536558592 1136778848 12.185 100 15 30 0 1212810144 73022704 3008592
03:23:45 80548 8192000000 10102976624 10532413440 1126841520 12.092 98 37 70 0 1175990864 42056464 7092880
03:26:13 69194 8192000000 10110832256 10587955200 1128606944 12.108 98 20 41 0 1224375520 91677024 4091552
03:29:20 61395 8192000000 10079504416 10536456192 1111210576 11.944 97 22 45 3 1139336656 23917184 4208896
03:31:28 51340 8192000000 10056761040 10478018560 1092612304 11.767 96 30 53 1 1119046928 20388384 6046240
03:33:21 21380 8192000000 10118554320 10543357952 1131995968 12.140 100 64 126 0 1208144128 63127584 13020576
03:34:44 74301 8192000000 10078155488 10546601984 1135713760 12.175 100 49 97 6 1182375136 35746608 10914768
03:36:59 19219 8192000000 10079373872 10530754560 1117040880 11.999 99 52 102 0 1161442000 33002352 11398768
03:38:22 10075 8192000000 10101178416 10542301184 1120022864 12.027 100 38 76 0 1194271472 65555632 8692976
03:39:34 17196 8192000000 10110514912 10534739968 1121168816 12.038 99 49 98 3 1196729216 64308752 11251648
03:40:53 19441 8192000000 10159660224 10594484224 1143839088 12.252 100 37 70 1 1254223792 102729040 7655664
03:42:30 2855 8192000000 10196657904 10617180160 1148763744 12.298 100 65 122 2 1288822640 126293136 13765760
03:43:36 9231 8192000000 10234864176 10668310528 1125663504 12.080 99 51 101 2 1329769968 192091312 12015152
03:44:49 52103 8192000000 10159969840 10680881152 1112598048 11.957 99 14 28 0 1279714480 163448112 3668320
03:46:47 71017 8192000000 10137526208 10633920512 1112088400 11.952 99 13 26 0 1258282944 143049104 3145440
03:49:02 83904 8192000000 10045268672 10541137920 1070957488 11.561 96 7 14 0 1135102816 62935040 1210288
03:51:27 76159 8192000000 10064655136 10540892160 1093309104 11.774 97 15 30 0 1139727504 42828832 3589568
03:53:54 30368 8192000000 10119691648 10559090688 1117807392 12.006 98 24 46 2 1205634800 82976624 4850784
03:55:32 44995 8192000000 10155663920 10615701504 1132645568 12.146 99 40 79 0 1240834016 100996368 7192080
03:57:26 92205 8192000000 10189282608 10625552384 1107546192 11.909 97 16 32 0 1222763504 111872688 3344624
04:00:20 15774 8192000000 10095342768 10527682560 1126348400 12.087 99 42 84 1 1189799536 54703152 8747984
04:01:39 25440 8192000000 10092781696 10520993792 1088201008 11.726 96 36 72 1 1137594736 42039472 7354256
04:03:13 62902 8192000000 10108278080 10556567552 1109781184 11.930 98 25 50 0 1191892752 77262352 4849216
04:05:16 126736 8192000000 10049425728 10518228992 1105626864 11.891 98 41 82 1 1164720624 50779600 8314160
04:08:24 55219 8192000000 10094093776 10526408704 1112102192 11.952 99 41 82 0 1194753824 74677568 7974064
04:10:24 39463 8192000000 10161838592 10614501376 1140956128 12.225 100 37 72 0 1247746112 98690176 8099808
04:12:28 56388 8192000000 10163461424 10639613952 1142592656 12.240 99 27 54 1 1267323408 118601520 6129232
04:14:25 70312 8192000000 10150444112 10637168640 1136734432 12.185 99 14 29 1 1269701520 129184368 3782720
04:17:03 60473 8192000000 10108240752 10561691648 1132079728 12.141 100 12 24 0 1216856976 81992560 2784688
04:19:07 64978 8192000000 10103341776 10550345728 1098377984 11.822 96 22 44 1 1162174656 59110608 4686064
04:21:14 35894 8192000000 10142820544 10637578240 1139348640 12.209 100 33 66 0 1235201488 89009280 6843568
04:22:55 22585 8192000000 10178444000 10615693312 1146443520 12.276 100 53 99 0 1269053184 112135520 10474144
04:24:35 53457 8192000000 10138591488 10605768704 1099128176 11.829 96 14 26 2 1219112448 117454160 2530112
04:26:35 81553 8192000000 10120696640 10599292928 1084290176 11.688 96 23 46 0 1160992832 72037488 4665168
04:29:01 110795 8192000000 10057152720 10502070272 1119364656 12.021 99 26 52 0 1158708704 33352832 5991216
04:32:06 17661 8192000000 10098504224 10526298112 1131810128 12.138 100 55 110 1 1192270864 49261232 11199504
04:33:25 44718 8192000000 10070141888 10545238016 1136055968 12.178 100 7 14 0 1183576544 46025248 1495328
04:35:13 83417 8192000000 10073556576 10520764416 1128267280 12.105 99 22 44 1 1165893920 32662080 4964560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-06-2022 04:40:12 to 17-06-2022 04:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2022 04:40:29 to 17-06-2022 04:40:31.

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          219342
MON$OLDEST_ACTIVE               221943
MON$OLDEST_SNAPSHOT             221943
MON$NEXT_TRANSACTION            221943
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                       2128256
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Fri Jun 17 04:37:23 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Fri Jun 17 04:37:25 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

17.06.22 04:40:32. 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-06-17 04:40:34 No severe PSQL-related problems occured
17.06.22 04:40:36. End of report.