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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14755312 kB
MemAvailable 30390392 kB
Buffers 0 kB
Cached 16928164 kB
SwapCached 1580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-26 10:37:24
DTS_END                         2023-01-26 10:37:24.7190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40740
TRN_ID                          217448
ATT_ID                          578
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: 9196.69 24436 1103603
customer order: creation 170.45 448 20454
customer order: refuse 14.01 391 1681
order to supplier: creation 45.17 1347 5420
order to supplier: removal 3.27 172851 392
invoice (draft): creation 45.13 3174 5415
invoice (draft): removal 53.27 899 6392
invoice accept: apply 41.28 24148 4953
invoice accept: cancel 48.47 1111 5816
customer reserve: creation 3788.93 229 454672
customer reserve: removal 4689.54 94 562745
realization accept: apply 81.10 275 9732
realization accept: cancel 89.03 148 10684
payment from customer: creation 59.02 169 7082
payment from customer: removal 5.29 141 635
payment to supplier: creation 47.56 182 5707
payment to supplier: removal 3.31 164 397
service: total inventory turnovers 3.74 4244 449
service: total monetary turnovers 7.25 444 870
service: aggregate perf. data 0.86 18906 103
service: refresh index statistics 0.03 259360 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-01-2023 10:38:43 to 26-01-2023 10:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8294
TEST_TIME 2 7864
TEST_TIME 3 7033
TEST_TIME 4 6810
TEST_TIME 5 6886
TEST_TIME 6 6786
TEST_TIME 7 6778
TEST_TIME 8 7231
TEST_TIME 9 7281
TEST_TIME 10 7171
TEST_TIME 11 7100
TEST_TIME 12 7639
TEST_TIME 13 7580
TEST_TIME 14 7775
TEST_TIME 15 7686
TEST_TIME 16 7764
TEST_TIME 17 7675
TEST_TIME 18 7654
TEST_TIME 19 8280
TEST_TIME 20 8655
TEST_TIME 21 8557
TEST_TIME 22 8544
TEST_TIME 23 8625
TEST_TIME 24 8793
TEST_TIME 25 8919
TEST_TIME 26 9051
TEST_TIME 27 9085
TEST_TIME 28 9242
TEST_TIME 29 9278
TEST_TIME 30 9771
TEST_TIME 31 9887
TEST_TIME 32 9876
TEST_TIME 33 10214
TEST_TIME 34 10361
TEST_TIME 35 10394
TEST_TIME 36 10817
TEST_TIME 37 11067
TEST_TIME 38 11039
TEST_TIME 39 11189
TEST_TIME 40 11015
TEST_TIME 41 10908
TEST_TIME 42 10831
TEST_TIME 43 10858
TEST_TIME 44 10753
TEST_TIME 45 10759
TEST_TIME 46 10676
TEST_TIME 47 10614
TEST_TIME 48 10439
TEST_TIME 49 10265
TEST_TIME 50 10357
TEST_TIME 51 10233
TEST_TIME 52 10170
TEST_TIME 53 10040
TEST_TIME 54 10056
TEST_TIME 55 9985
TEST_TIME 56 9867
TEST_TIME 57 9897
TEST_TIME 58 9812
TEST_TIME 59 9802
TEST_TIME 60 9927
TEST_TIME 61 9938
TEST_TIME 62 9931
TEST_TIME 63 9899
TEST_TIME 64 9994
TEST_TIME 65 9925
TEST_TIME 66 9890
TEST_TIME 67 9854
TEST_TIME 68 9879
TEST_TIME 69 9818
TEST_TIME 70 9873
TEST_TIME 71 9839
TEST_TIME 72 9768
TEST_TIME 73 9962
TEST_TIME 74 9935
TEST_TIME 75 9899
TEST_TIME 76 9940
TEST_TIME 77 9956
TEST_TIME 78 9951
TEST_TIME 79 9832
TEST_TIME 80 9842
TEST_TIME 81 9783
TEST_TIME 82 9759
TEST_TIME 83 9709
TEST_TIME 84 9722
TEST_TIME 85 9690
TEST_TIME 86 9634
TEST_TIME 87 9592
TEST_TIME 88 9518
TEST_TIME 89 9457
TEST_TIME 90 9484
TEST_TIME 91 9429
TEST_TIME 92 9435
TEST_TIME 93 9397
TEST_TIME 94 9441
TEST_TIME 95 9408
TEST_TIME 96 9391
TEST_TIME 97 9390
TEST_TIME 98 9426
TEST_TIME 99 9428
TEST_TIME 100 9430
TEST_TIME 101 9498
TEST_TIME 102 9506
TEST_TIME 103 9611
TEST_TIME 104 9567
TEST_TIME 105 9545
TEST_TIME 106 9497
TEST_TIME 107 9474
TEST_TIME 108 9451
TEST_TIME 109 9447
TEST_TIME 110 9380
TEST_TIME 111 9359
TEST_TIME 112 9321
TEST_TIME 113 9283
TEST_TIME 114 9257
TEST_TIME 115 9226
TEST_TIME 116 9238
TEST_TIME 117 9218
TEST_TIME 118 9197
TEST_TIME 119 9257
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-01-2023 10:38:47 to 26-01-2023 10:38:50.

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 1521690 1521690 0 0.00 0 23101 12 0 0
doc_list_biud 1521670 1521670 0 0.00 0 31781 41 0 0
sp_add_invoice_to_stock 5070 4953 117 2.30 45 194431 24148 0 117
sp_cancel_adding_invoice 5816 5816 0 0.00 5 130721 1111 0 0
sp_cancel_client_order 1681 1681 0 0.00 5 11647 391 0 0
sp_cancel_customer_reserve 562745 562745 0 0.00 1 31813 95 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 6129 142 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 14270 164 0 0
sp_cancel_supplier_invoice 6402 6392 10 0.15 2 18301 900 0 10
sp_cancel_supplier_order 392 392 0 0.00 8 1348409 172851 0 0
sp_cancel_write_off 10684 10684 0 0.00 2 21628 148 0 0
sp_client_order 20454 20454 0 0.00 5 22814 449 0 0
sp_customer_reserve 454672 454672 0 0.00 0 105229 230 0 0
sp_fill_shopping_cart 31282 31282 0 0.00 1 26400 84 0 0
sp_fill_shopping_cart_clo_res 454642 454642 0 0.00 0 21854 18 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 26787 326 0 0
sp_kill_cost_storno 17532 17532 0 0.00 0 10151 72 0 0
sp_kill_qstorno_ret_qs2qd 1142958 1142958 0 0.00 0 31701 41 0 0
sp_kill_qty_storno 602344 602344 0 0.00 0 31778 95 0 0
sp_lock_selected_doc 20270 20270 0 0.00 0 9666 8 0 0
sp_make_cost_storno 27425 27425 0 0.00 0 23091 75 0 0
sp_multiply_rows_for_pdistr 27423 27423 0 0.00 0 11091 39 0 0
sp_multiply_rows_for_qdistr 20453 20453 0 0.00 0 22759 172 0 0
sp_pay_from_customer 7082 7082 0 0.00 5 11141 169 0 0
sp_pay_to_supplier 5707 5707 0 0.00 7 12762 182 0 0
sp_payment_common 12787 12787 0 0.00 2 10664 45 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 11636 323 0 0
sp_qd_handle_on_invoice_upd_sts 10720 10720 0 0.00 0 23313 517 0 0
sp_qd_handle_on_reserve_upd_sts 9730 9730 0 0.00 0 22270 61 0 0
sp_reserve_write_off 9732 9732 0 0.00 8 22310 275 0 0
sp_supplier_invoice 5415 5415 0 0.00 9 83330 3175 0 0
sp_supplier_order 5420 5420 0 0.00 6 41573 1347 0 0
srv_aggregate_perf_data 103 103 0 0.00 2279 169647 18907 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 830 151601 38337 0 0
srv_find_qd_qs_mism 1055116 1055116 0 0.00 0 17835 8 0 0
srv_make_invnt_saldo 449 449 0 0.00 57 70870 4244 0 0
srv_make_money_saldo 870 870 0 0.00 1 19758 445 0 0
srv_recalc_idx_stat 4 4 0 0.00 55875 399313 259361 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4960 4960 0 0.00 1 10964 954 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 6238 393 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 4790 33 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 3298 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2850 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 2027 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1069 34 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 100279 7611 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 956 18 0 0
t$perf-norm:sp_client_order 20454 20454 0 0.00 0 4298 36 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 25125 45 0 0
t$perf-norm:sp_pay_from_customer 7082 7082 0 0.00 0 7530 19 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 5575 21 0 0
t$perf-norm:sp_reserve_write_off 9732 9732 0 0.00 0 4935 23 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 4103 32 0 0
t$perf-norm:sp_supplier_order 5421 5421 0 0.00 0 4954 32 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 401 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 129 6 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 561 12 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 924 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 22 309 98 0 0
v_add_invoice_to_stock 10014 9780 234 2.33 0 3613 24 0 234
v_all_customers 37940 37940 0 0.00 0 132 4 0 0
v_all_suppliers 29910 29910 0 0.00 0 88 4 0 0
v_all_wares 40898 40898 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 598 14 0 0
v_cancel_client_order 3362 3362 0 0.00 0 2656 10 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 560 11 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 1 402 9 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2093 12 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 828 23 0 20
v_cancel_supplier_order 784 784 0 0.00 0 837 11 0 0
v_cancel_write_off 2014 2014 0 0.00 0 689 22 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 503 5 0 0
v_max_id_clo_res 997 997 0 0.00 0 2481 14 0 0
v_max_id_ord_sup 5412 5412 0 0.00 0 10157 10 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 1495 10 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 1598 9 0 0
v_min_id_clo_ord 5416 5416 0 0.00 0 2573 8 0 0
v_min_id_clo_res 997 997 0 0.00 0 4672 16 0 0
v_min_id_ord_sup 5412 5412 0 0.00 0 6750 9 0 0
v_min_non_paid_invoice 5705 5705 0 0.00 0 5787 15 0 0
v_min_non_paid_realizn 7081 7081 0 0.00 0 10658 20 0 0
v_our_firm 10070 10070 0 0.00 0 124 5 0 0
v_reserve_write_off 19458 19458 0 0.00 0 3155 10 0 0
x_lock_depdocs_on_canc_invoice 11805 11805 0 0.00 0 11947 112 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 4205 363 0 0
x_make_qstorno_1000_1200 5420 5420 0 0.00 2 41554 1180 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 4 83184 2940 0 0
x_make_qstorno_2100_3300 454670 454670 0 0.00 0 105225 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-01-2023 10:38:51 to 26-01-2023 10:38:55.

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:28 4 8192000000 8802907936 8815828992 7850640 0.095 3 2 2 1 8023232 110192 62400
08:08:29 25505 8192000000 9728962608 10031333376 799914352 8.895 100 88 176 0 829621520 7408016 22299152
08:09:58 17660 8192000000 9772662448 10081439744 843815232 9.338 100 94 186 1 877541616 11727744 21998640
08:11:22 6185 8192000000 9794112656 10108084224 866126752 9.561 100 93 186 0 905910544 18302432 21481360
08:12:37 6882 8192000000 9857750352 10191904768 911033008 10.008 100 77 152 0 968616048 40913952 16669088
08:13:51 5349 8192000000 9908710368 10251759616 959260784 10.482 100 86 172 1 1029962304 52591568 18109952
08:14:59 1749 8192000000 9976871696 10329313280 988581072 10.768 100 93 180 1 1092195328 84281168 19333088
08:16:02 1639 8192000000 9988359792 10350342144 1015764272 11.031 100 88 169 0 1100272624 67341728 17166624
08:17:13 1270 8192000000 9994981392 10365415424 1038970256 11.255 100 96 187 1 1122113936 63474448 19669232
08:18:17 1721 8192000000 10033232688 10410774528 1055784672 11.416 100 92 182 1 1154582032 80374880 18422480
08:19:21 921 8192000000 10042458992 10422169600 1062193488 11.477 100 98 190 2 1164584720 83424768 18966464
08:20:24 1117 8192000000 10042833984 10424029184 1073267856 11.583 100 95 181 6 1171847568 80222432 18357280
08:21:27 1159 8192000000 10071396832 10456137728 1083828160 11.684 100 97 180 1 1192609392 91123360 17657872
08:22:30 1102 8192000000 10049172384 10434674688 1094487056 11.785 100 94 184 0 1172032752 58584544 18961152
08:23:32 1400 8192000000 10073479712 10461921280 1102338960 11.860 100 95 188 2 1191104096 70793792 17971344
08:24:35 1102 8192000000 10078551200 10467024896 1101387584 11.851 100 92 179 1 1200685744 81082544 18215616
08:25:37 1231 8192000000 10072236448 10465050624 1108781136 11.921 100 92 178 0 1200172608 71459696 19931776
08:26:39 794 8192000000 10065166640 10457493504 1105826000 11.893 100 94 183 2 1192973888 66691744 20456144
08:27:42 3334 8192000000 10064756736 10449653760 1100482608 11.842 100 89 170 0 1186563568 69655216 16425744
08:28:46 1276 8192000000 10052193248 10442248192 1104763808 11.883 100 87 161 0 1174840560 53926608 16150144
08:29:48 1391 8192000000 10037560976 10422112256 1087164240 11.716 99 86 161 2 1158414400 53798688 17451472
08:30:51 2863 8192000000 10051735264 10437193728 1097460528 11.814 100 81 153 2 1169739584 55417056 16862000
08:31:56 897 8192000000 10042270880 10433896448 1079509536 11.643 100 87 166 1 1165796304 69343088 16943680
08:33:00 997 8192000000 10019045792 10397802496 1052494848 11.385 99 96 183 0 1138649376 66474656 19679872
08:34:03 911 8192000000 10020014816 10398355456 1053977280 11.399 100 95 176 0 1141441232 68239808 19224144
08:35:05 1038 8192000000 10011344832 10387738624 1043024848 11.294 100 91 179 1 1134079712 72225248 18829616
08:36:07 1392 8192000000 10010080832 10394759168 1046584176 11.328 100 80 156 1 1138242080 74402528 17255376
08:37:10 1400 8192000000 10007122080 10385268736 1052347040 11.383 100 92 172 2 1131379712 61401168 17631504
08:38:16 17999 8192000000 10025342880 10400903168 1061934816 11.475 99 80 142 0 1140390608 64105520 14350272
08:39:39 1689 8192000000 10053091296 10432163840 1083287744 11.679 100 89 162 2 1170806448 71251184 16267520
08:40:45 4021 8192000000 10071769472 10451664896 1087610224 11.720 99 86 168 3 1190700320 84219712 18870384
08:41:53 4731 8192000000 10080656704 10471718912 1096303952 11.803 99 69 131 0 1195754160 85724480 13725728
08:43:04 19738 8192000000 10123370688 10529370112 1118433632 12.012 100 56 106 1 1238155232 108552128 11169472
08:44:37 4870 8192000000 10165064784 10567909376 1128461808 12.107 100 70 138 1 1280714560 137135904 15116848
08:45:45 1140 8192000000 10214338176 10606321664 1144483152 12.258 100 97 190 0 1328186688 162588064 21115472
08:46:50 4005 8192000000 10249485952 10654158848 1152519616 12.333 100 82 155 0 1359908672 190337328 17051728
08:48:00 1723 8192000000 10258026800 10659602432 1157122368 12.376 100 83 160 1 1369755232 194555168 18077696
08:49:09 2416 8192000000 10278372288 10711236608 1160106976 12.404 100 80 151 0 1390328240 212919232 17302032
08:50:14 2257 8192000000 10294383840 10711785472 1168430784 12.482 100 82 152 0 1405156496 219609168 17116544
08:51:20 1424 8192000000 10315755488 10713694208 1173191808 12.527 99 90 168 1 1419044384 228038064 17814512
08:52:25 1198 8192000000 10363591792 10764468224 1175784960 12.551 100 99 197 1 1471098656 273102624 22211072
08:53:30 1115 8192000000 10395608032 10813947904 1183814656 12.626 100 96 186 1 1505530944 299502240 22214048
08:54:33 822 8192000000 10430191648 10837909504 1195004672 12.730 100 100 191 0 1536683600 318937344 22741584
08:55:39 1319 8192000000 10430081184 10888253440 1199593840 12.773 100 95 184 1 1536609376 314234576 22780960
08:56:43 1607 8192000000 10422216048 10903441408 1211966368 12.887 100 95 179 1 1528108352 294845216 21296768
08:57:48 8032 8192000000 10398081008 10836361216 1205986480 12.832 100 53 104 2 1514515984 294907008 13622496
08:59:07 1536 8192000000 10418417152 10852122624 1195871088 12.738 100 93 182 1 1527155296 307034400 24249808
09:00:19 5501 8192000000 10422860160 10868674560 1195606656 12.736 100 52 102 0 1535107648 327386768 12114224
09:01:28 6127 8192000000 10444683328 10890571776 1182555872 12.614 100 70 132 1 1554828800 355891296 16381632
09:02:49 8596 8192000000 10439760816 10909315072 1180356768 12.594 100 54 108 0 1549552176 355993376 13202032
09:03:59 20195 8192000000 10439055120 10891231232 1161423776 12.417 99 45 87 0 1535295872 363034016 10838080
09:05:25 3005 8192000000 10451340128 10901233664 1170279616 12.499 100 67 134 0 1555502448 368710080 16512752
09:06:34 6583 8192000000 10436273312 10910830592 1151503040 12.324 98 45 89 0 1529921184 367506224 10911920
09:07:43 18682 8192000000 10375667616 10842611712 1154658928 12.353 99 37 74 0 1486963840 323029856 9275056
09:09:03 13359 8192000000 10388822992 10840051712 1141245584 12.227 98 27 54 0 1486122464 338065888 6810992
09:10:19 40905 8192000000 10323739232 10797178880 1142030448 12.235 98 31 60 0 1454404752 304771136 7603168
09:12:00 24581 8192000000 10317819152 10750267392 1120977680 12.036 98 46 90 0 1397498304 266294928 10225696
09:13:26 41932 8192000000 10111685344 10657845248 1112066960 11.952 99 10 20 0 1261597552 147014256 2516336
09:17:34 62786 8192000000 10066733600 10491232256 1127301248 12.096 100 27 54 0 1182044112 47453376 7289488
09:19:37 44594 8192000000 10092694336 10562674688 1128334448 12.106 100 8 16 0 1197398208 67308944 1754816
09:21:22 98240 8192000000 10075405360 10561482752 1088898880 11.732 96 10 20 0 1158563744 67839296 1825568
09:24:02 151601 8192000000 10099644400 10532114432 1108692944 11.920 98 30 54 0 1167594160 53226016 5675200
09:27:34 62655 8192000000 10096244160 10541580288 1116264512 11.992 99 26 52 1 1190736752 68512448 5959792
09:29:45 62652 8192000000 10097124080 10526040064 1120920656 12.036 98 63 127 0 1172826240 37989504 13916080
09:31:49 50469 8192000000 10148930576 10593554432 1151853424 12.327 100 25 49 0 1228243616 70736048 5654144
09:33:44 9199 8192000000 10212126928 10645848064 1164258320 12.443 100 53 102 2 1307552848 130292992 13001536
09:34:57 66368 8192000000 10256085216 10705178624 1166561600 12.465 100 16 31 0 1339226608 169093344 3571664
09:38:13 38807 8192000000 10148202480 10649968640 1164071184 12.441 100 11 22 0 1272934304 106650016 2213104
09:39:54 41604 8192000000 10135836784 10603409408 1163936592 12.440 100 14 26 1 1225325616 58454496 2934528
09:41:35 98832 8192000000 10089892912 10542276608 1106549984 11.900 95 19 38 0 1156220944 45824816 3846144
09:44:15 25766 8192000000 10092242576 10525519872 1142305008 12.237 100 53 98 0 1190438208 37576016 10557184
09:45:44 149122 8192000000 10130547808 10647588864 1112732832 11.958 96 18 35 0 1188744400 72215840 3795728
09:49:15 69489 8192000000 10151450672 10671087616 1103381376 11.870 96 30 60 0 1235954832 126525760 6047696
09:51:35 35315 8192000000 10110560320 10556231680 1146125440 12.273 100 40 72 1 1200739392 47249456 7364496
09:53:14 122503 8192000000 10094746864 10592280576 1086811696 11.712 95 15 30 0 1158983536 69074576 3097264
09:56:17 90132 8192000000 10039530560 10515906560 1090670912 11.749 97 35 70 0 1143441392 44847024 7923456
09:58:54 66171 8192000000 10100158192 10586394624 1120677168 12.033 100 35 67 0 1198955552 71279536 6998848
10:01:04 150648 8192000000 10044547936 10519449600 1076163360 11.611 95 16 32 0 1114796256 35355024 3277872
10:04:35 59892 8192000000 10072828240 10532823040 1118184656 12.010 99 14 28 0 1166076608 44969360 2922592
10:06:35 73123 8192000000 10053790592 10557116416 1090031216 11.743 96 21 42 0 1150473088 56284976 4156896
10:09:33 46697 8192000000 10069792688 10513297408 1073781408 11.588 95 17 34 0 1140485648 63428032 3276208
10:11:24 27394 8192000000 10091472944 10565980160 1098728656 11.826 98 28 56 0 1196491440 91499840 6262944
10:13:03 18945 8192000000 10134608976 10564083712 1105966160 11.894 98 41 81 0 1219766272 104674112 9126000
10:14:26 16208 8192000000 10170260288 10612703232 1102838576 11.865 98 42 84 0 1249354928 137111120 9405232
10:15:45 47873 8192000000 10169622624 10613047296 1129022384 12.112 100 24 47 0 1263640272 129122752 5495136
10:17:33 37732 8192000000 10177500432 10650615808 1137713792 12.194 100 21 42 0 1283719184 140679184 5326208
10:19:24 8522 8192000000 10156238208 10588467200 1141203760 12.227 100 49 91 0 1238824000 88254288 9365952
10:20:34 33775 8192000000 10112361312 10570145792 1133928592 12.158 100 31 62 0 1211344480 71254336 6161552
10:22:08 77330 8192000000 10125151184 10601668608 1131676416 12.137 99 44 88 0 1228451056 87789056 8985584
10:24:40 32519 8192000000 10113835968 10576850944 1140188080 12.217 100 45 90 0 1205470784 55939392 9343312
10:26:14 75985 8192000000 10075187952 10557190144 1144395008 12.257 100 4 8 0 1180390000 35177040 817952
10:28:30 82414 8192000000 10088572864 10582761472 1152919584 12.337 100 15 30 0 1198334880 42292352 3122944
10:30:52 60995 8192000000 10120675696 10591326208 1141545120 12.230 99 16 31 0 1220085600 75078144 3462336
10:34:07 78418 8192000000 10133486384 10585788416 1105860768 11.893 96 14 24 0 1211851248 103413248 2577232
10:36:26 22415 8192000000 10076009280 10522664960 1093999728 11.781 96 19 39 0 1142230288 43841264 4389296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-01-2023 10:38:57 to 26-01-2023 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          215715
MON$OLDEST_ACTIVE               218328
MON$OLDEST_SNAPSHOT             218328
MON$NEXT_TRANSACTION            218328
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                       2094656
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2891 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2891 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2891 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 Jan 26 10:37:30 2023
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Thu Jan 26 10:37:32 2023
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
--- end of diff output ---

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

26.01.23 10:39: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
2023-01-26 10:39:20 No severe PSQL-related problems occured
26.01.23 10:39:22. End of report.