Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.05.2022 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 15908476 kB
MemAvailable 31345704 kB
Buffers 0 kB
Cached 15760612 kB
SwapCached 2452 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-05-28 10:37:16
DTS_END                         2022-05-28 10:37:16.2710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55596
TRN_ID                          227357
ATT_ID                          615
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: 9028.19 21278 1083383
customer order: creation 178.20 480 21384
customer order: refuse 15.04 391 1805
order to supplier: creation 46.84 1311 5621
order to supplier: removal 3.43 154820 411
invoice (draft): creation 47.32 3050 5678
invoice (draft): removal 50.01 953 6001
invoice accept: apply 42.45 26210 5094
invoice accept: cancel 44.99 1373 5399
customer reserve: creation 3940.82 248 472898
customer reserve: removal 4364.03 97 523684
realization accept: apply 84.43 279 10132
realization accept: cancel 79.21 155 9505
payment from customer: creation 59.44 183 7133
payment from customer: removal 5.55 137 666
payment to supplier: creation 50.37 204 6044
payment to supplier: removal 3.52 128 422
service: total inventory turnovers 3.80 4600 456
service: total monetary turnovers 7.75 349 930
service: aggregate perf. data 0.96 16162 115
service: refresh index statistics 0.04 214428 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-05-2022 10:38:23 to 28-05-2022 10:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8242
TEST_TIME 2 7561
TEST_TIME 3 6036
TEST_TIME 4 6498
TEST_TIME 5 6254
TEST_TIME 6 6703
TEST_TIME 7 6824
TEST_TIME 8 6971
TEST_TIME 9 6805
TEST_TIME 10 6952
TEST_TIME 11 6887
TEST_TIME 12 7415
TEST_TIME 13 7613
TEST_TIME 14 7844
TEST_TIME 15 8094
TEST_TIME 16 8209
TEST_TIME 17 8164
TEST_TIME 18 8256
TEST_TIME 19 8533
TEST_TIME 20 8612
TEST_TIME 21 8758
TEST_TIME 22 8859
TEST_TIME 23 8936
TEST_TIME 24 9211
TEST_TIME 25 9244
TEST_TIME 26 9186
TEST_TIME 27 9214
TEST_TIME 28 9526
TEST_TIME 29 9599
TEST_TIME 30 9670
TEST_TIME 31 10044
TEST_TIME 32 10096
TEST_TIME 33 10522
TEST_TIME 34 10727
TEST_TIME 35 10868
TEST_TIME 36 11032
TEST_TIME 37 10851
TEST_TIME 38 11245
TEST_TIME 39 11037
TEST_TIME 40 10941
TEST_TIME 41 10859
TEST_TIME 42 10865
TEST_TIME 43 10814
TEST_TIME 44 10686
TEST_TIME 45 10633
TEST_TIME 46 10428
TEST_TIME 47 10332
TEST_TIME 48 10250
TEST_TIME 49 10126
TEST_TIME 50 10037
TEST_TIME 51 10036
TEST_TIME 52 9969
TEST_TIME 53 10035
TEST_TIME 54 10045
TEST_TIME 55 9985
TEST_TIME 56 9874
TEST_TIME 57 9835
TEST_TIME 58 9708
TEST_TIME 59 9696
TEST_TIME 60 9732
TEST_TIME 61 9808
TEST_TIME 62 9757
TEST_TIME 63 9702
TEST_TIME 64 9768
TEST_TIME 65 9809
TEST_TIME 66 9796
TEST_TIME 67 9829
TEST_TIME 68 9807
TEST_TIME 69 9761
TEST_TIME 70 9836
TEST_TIME 71 9790
TEST_TIME 72 9815
TEST_TIME 73 9821
TEST_TIME 74 9740
TEST_TIME 75 9652
TEST_TIME 76 9694
TEST_TIME 77 9600
TEST_TIME 78 9543
TEST_TIME 79 9504
TEST_TIME 80 9451
TEST_TIME 81 9506
TEST_TIME 82 9524
TEST_TIME 83 9473
TEST_TIME 84 9467
TEST_TIME 85 9454
TEST_TIME 86 9449
TEST_TIME 87 9390
TEST_TIME 88 9432
TEST_TIME 89 9395
TEST_TIME 90 9371
TEST_TIME 91 9486
TEST_TIME 92 9519
TEST_TIME 93 9533
TEST_TIME 94 9529
TEST_TIME 95 9489
TEST_TIME 96 9508
TEST_TIME 97 9472
TEST_TIME 98 9428
TEST_TIME 99 9387
TEST_TIME 100 9375
TEST_TIME 101 9311
TEST_TIME 102 9271
TEST_TIME 103 9225
TEST_TIME 104 9199
TEST_TIME 105 9163
TEST_TIME 106 9221
TEST_TIME 107 9176
TEST_TIME 108 9136
TEST_TIME 109 9088
TEST_TIME 110 9100
TEST_TIME 111 9084
TEST_TIME 112 9077
TEST_TIME 113 9049
TEST_TIME 114 9095
TEST_TIME 115 9075
TEST_TIME 116 9062
TEST_TIME 117 9058
TEST_TIME 118 9064
TEST_TIME 119 9069
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-05-2022 10:38:27 to 28-05-2022 10:38:30.

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 1518148 1518148 0 0.00 0 26333 14 0 0
doc_list_biud 1518136 1518136 0 0.00 0 33119 39 0 0
sp_add_invoice_to_stock 5254 5094 160 3.04 56 215014 26211 0 160
sp_cancel_adding_invoice 5399 5399 0 0.00 6 137179 1373 0 0
sp_cancel_client_order 1805 1805 0 0.00 6 8570 392 0 0
sp_cancel_customer_reserve 523684 523684 0 0.00 1 33121 97 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 4 6658 138 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 6697 128 0 0
sp_cancel_supplier_invoice 6009 6001 8 0.13 3 12855 954 0 8
sp_cancel_supplier_order 411 411 0 0.00 19 1371725 154821 0 0
sp_cancel_write_off 9505 9505 0 0.00 2 8828 155 0 0
sp_client_order 21384 21384 0 0.00 6 32257 480 0 0
sp_customer_reserve 472898 472898 0 0.00 1 82547 248 0 0
sp_fill_shopping_cart 32658 32658 0 0.00 1 20506 90 0 0
sp_fill_shopping_cart_clo_res 472870 472870 0 0.00 0 14318 18 0 0
sp_get_clo_for_invoice 5058 5058 0 0.00 0 21588 268 0 0
sp_kill_cost_storno 15992 15992 0 0.00 0 7034 67 0 0
sp_kill_qstorno_ret_qs2qd 1063285 1063285 0 0.00 0 33116 42 0 0
sp_kill_qty_storno 561994 561994 0 0.00 0 33119 97 0 0
sp_lock_selected_doc 21094 21094 0 0.00 0 5733 7 0 0
sp_make_cost_storno 28367 28367 0 0.00 0 26323 83 0 0
sp_multiply_rows_for_pdistr 28367 28367 0 0.00 0 9017 39 0 0
sp_multiply_rows_for_qdistr 21383 21383 0 0.00 0 10380 168 0 0
sp_pay_from_customer 7133 7133 0 0.00 6 41009 183 0 0
sp_pay_to_supplier 6044 6044 0 0.00 6 10253 204 0 0
sp_payment_common 13175 13175 0 0.00 2 40987 55 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 1 8531 317 0 0
sp_qd_handle_on_invoice_upd_sts 10456 10456 0 0.00 0 13669 537 0 0
sp_qd_handle_on_reserve_upd_sts 10132 10132 0 0.00 0 11870 61 0 0
sp_reserve_write_off 10132 10132 0 0.00 9 26344 279 0 0
sp_supplier_invoice 5678 5678 0 0.00 10 97494 3050 0 0
sp_supplier_order 5621 5621 0 0.00 7 39199 1312 0 0
srv_aggregate_perf_data 115 115 0 0.00 3114 145575 16163 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 864 162354 36050 0 0
srv_find_qd_qs_mism 1032953 1032953 0 0.00 0 9495 11 0 0
srv_make_invnt_saldo 456 456 0 0.00 101 74850 4600 0 0
srv_make_money_saldo 930 930 0 0.00 1 18079 349 0 0
srv_recalc_idx_stat 5 5 0 0.00 60424 366161 214429 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 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 5097 5097 0 0.00 1 9916 1063 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 1 8717 578 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 2128 28 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 2788 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 4218 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1600 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 660 35 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 91458 7262 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 613 18 0 0
t$perf-norm:sp_client_order 21387 21387 0 0.00 0 9327 39 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 2069 17 0 0
t$perf-norm:sp_pay_from_customer 7133 7133 0 0.00 0 7480 21 0 0
t$perf-norm:sp_pay_to_supplier 6044 6044 0 0.00 0 8807 24 0 0
t$perf-norm:sp_reserve_write_off 10132 10132 0 0.00 0 8039 25 0 0
t$perf-norm:sp_supplier_invoice 5678 5678 0 0.00 0 4232 33 0 0
t$perf-norm:sp_supplier_order 5621 5621 0 0.00 0 4412 33 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 433 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 412 10 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 937 13 0 0
t$perf-norm:srv_make_money_saldo 930 930 0 0.00 0 260 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 172 48 0 0
v_add_invoice_to_stock 10424 10104 320 3.06 0 5347 28 0 320
v_all_customers 38514 38514 0 0.00 0 104 4 0 0
v_all_suppliers 31278 31278 0 0.00 0 106 5 0 0
v_all_wares 42758 42758 0 0.00 0 229 4 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1431 17 0 0
v_cancel_client_order 3610 3610 0 0.00 0 1786 10 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4909 17 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 1 1484 13 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 300 11 0 0
v_cancel_supplier_invoice 840 824 16 1.90 0 4741 35 0 16
v_cancel_supplier_order 822 822 0 0.00 0 2670 17 0 0
v_cancel_write_off 2020 2020 0 0.00 0 1769 22 0 0
v_max_id_clo_ord 5619 5619 0 0.00 0 2059 6 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 16384 29 0 0
v_max_id_ord_sup 5660 5660 0 0.00 0 9563 8 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 1178 9 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 1395 8 0 0
v_min_id_clo_ord 5619 5619 0 0.00 0 3368 10 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 2095 13 0 0
v_min_id_ord_sup 5660 5660 0 0.00 0 6933 13 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 7749 21 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 40963 25 0 0
v_our_firm 10938 10938 0 0.00 0 58 5 0 0
v_reserve_write_off 20260 20260 0 0.00 0 6245 10 0 0
x_lock_depdocs_on_canc_invoice 10988 10988 0 0.00 0 32139 133 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 16054 360 0 0
x_make_qstorno_1000_1200 5621 5621 0 0.00 2 39188 1117 0 0
x_make_qstorno_1200_2000 5678 5678 0 0.00 3 97469 2816 0 0
x_make_qstorno_2100_3300 472896 472896 0 0.00 0 82545 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-05-2022 10:38:31 to 28-05-2022 10:38:35.

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:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 50796 8192000000 9751191248 10055131136 815998640 9.058 100 90 180 0 845614336 7915840 21699856
08:10:19 21591 8192000000 9790441552 10102902784 856691104 9.467 100 92 184 1 893098000 14626912 21779984
08:11:47 15208 8192000000 9834078272 10161729536 888733456 9.787 100 80 160 2 940280064 33189344 18357264
08:13:09 9560 8192000000 9896794064 10238263296 933563008 10.230 100 71 139 0 1000776592 52047584 15166000
08:14:25 5168 8192000000 9939014128 10286690304 973479104 10.621 100 88 174 3 1052764544 61240048 18045392
08:15:33 1386 8192000000 9963134736 10319654912 1003644656 10.914 100 90 180 0 1084095472 62363664 18087152
08:16:36 952 8192000000 10009079504 10374868992 1035751776 11.224 100 93 181 1 1132419600 77606112 19061712
08:17:38 1105 8192000000 10019281744 10395664384 1051455600 11.375 100 95 190 1 1141757824 71363520 18938704
08:18:41 4384 8192000000 10022564096 10394091520 1071613536 11.567 100 94 177 3 1139254672 50676352 16964784
08:19:47 1092 8192000000 10041221808 10422976512 1087261120 11.717 100 86 163 1 1166319264 63282960 15775184
08:20:49 1173 8192000000 10066675120 10449670144 1087480864 11.719 100 89 172 0 1184859744 79756608 17622272
08:21:51 4704 8192000000 10079060992 10460700672 1096429232 11.804 100 96 182 2 1194879872 80670800 17779840
08:22:57 1444 8192000000 10061557184 10449338368 1103542864 11.871 100 94 186 1 1180652976 58334368 18775744
08:24:02 1351 8192000000 10076930480 10466627584 1112256704 11.954 100 93 172 2 1204219600 74456320 17506576
08:25:04 1493 8192000000 10075716352 10466103296 1115308272 11.983 100 93 180 1 1201766928 67471216 18987440
08:26:07 1108 8192000000 10031315664 10414100480 1087932144 11.723 100 97 183 2 1156077920 50096128 18049648
08:27:09 4120 8192000000 10052169440 10446118912 1102457408 11.861 100 77 144 0 1176499120 58789248 15252464
08:28:14 1106 8192000000 10076110512 10467631104 1105118448 11.886 100 94 182 0 1196449040 72008224 19322368
08:29:16 1022 8192000000 10054104736 10442354688 1103104704 11.867 100 88 167 0 1176050512 56214944 16730864
08:30:17 1119 8192000000 10040386832 10429620224 1089267056 11.736 100 94 182 1 1164502928 55927184 19308688
08:31:20 1733 8192000000 10015967504 10408202240 1069480880 11.547 100 71 128 0 1144571376 61905408 13185088
08:32:22 1186 8192000000 10020760896 10401968128 1062565648 11.481 99 97 185 3 1147387040 65516848 19304544
08:33:25 1319 8192000000 10022482352 10404347904 1048704912 11.348 100 95 183 0 1146155808 78595504 18855392
08:34:27 1196 8192000000 10008489072 10388987904 1049223632 11.353 100 86 167 2 1125943552 60861392 15858528
08:35:29 1140 8192000000 10003517872 10380169216 1046428656 11.326 100 92 180 1 1122748960 57225488 19094816
08:36:31 992 8192000000 10031699712 10408923136 1056394080 11.422 100 100 188 2 1155367536 79213392 19760064
08:37:35 1357 8192000000 10007817344 10386034688 1061042096 11.466 100 83 150 0 1128895664 52306288 15547280
08:38:51 6983 8192000000 10039801696 10426667008 1082821408 11.674 100 75 148 0 1160184816 62377728 14985680
08:40:01 9145 8192000000 10052309648 10430500864 1089429728 11.737 100 90 163 1 1168998400 63158000 16410672
08:41:15 1372 8192000000 10085324928 10481647616 1100412208 11.842 100 65 126 0 1208118128 93942800 13763120
08:42:24 14680 8192000000 10122567264 10516623360 1119619056 12.023 100 67 128 0 1240537184 107845520 13072608
08:43:44 1133 8192000000 10168200480 10567204864 1133595648 12.155 100 97 187 0 1282260784 129461408 19203728
08:44:49 1313 8192000000 10206276640 10596589568 1144369504 12.257 100 99 189 1 1322632608 158721696 19541408
08:45:54 2127 8192000000 10218679856 10616803328 1153744016 12.345 100 86 170 0 1331122448 158669568 18708864
08:47:02 2241 8192000000 10246698048 10657034240 1150953744 12.318 100 83 159 2 1365470544 196410400 18106400
08:48:09 1749 8192000000 10284255888 10681647104 1155377120 12.360 100 87 170 1 1393216912 219298672 18541120
08:49:14 1282 8192000000 10277984672 10704146432 1159989168 12.403 100 96 190 2 1393783680 212620384 21174128
08:50:18 1879 8192000000 10298533024 10728206336 1164213312 12.443 100 85 168 1 1406344368 222272880 19858176
08:51:22 1640 8192000000 10311731824 10724122624 1167388160 12.472 100 90 173 0 1417804384 231077152 19339072
08:52:28 855 8192000000 10291424256 10691096576 1164341344 12.444 100 95 181 3 1399768944 215155632 20271968
08:53:31 936 8192000000 10288711248 10691301376 1130737728 12.128 98 89 170 2 1401590288 250306432 20546128
08:54:35 1094 8192000000 10347294848 10753904640 1163023504 12.432 100 93 182 1 1455375376 272124624 20227248
08:55:39 2094 8192000000 10347399632 10785886208 1165689760 12.457 100 83 165 0 1456137040 271720944 18726336
08:56:46 1204 8192000000 10357132112 10786500608 1175071936 12.544 100 90 169 1 1460572784 266338752 19162096
08:57:51 1122 8192000000 10374185488 10815279104 1168158352 12.480 100 90 173 1 1482016240 292754832 21103056
08:58:55 1937 8192000000 10378752160 10808463360 1177015728 12.562 100 74 145 0 1484216160 288859808 18340624
08:59:59 7925 8192000000 10368748448 10809364480 1150424240 12.313 99 53 106 0 1475058688 311405680 13228768
09:01:12 1254 8192000000 10430690944 10865422336 1167924160 12.477 100 95 178 0 1529838224 340196976 21717088
09:02:15 3838 8192000000 10416421376 10840387584 1165631552 12.456 100 66 127 1 1525814848 344743264 15440032
09:03:31 4253 8192000000 10416934784 10847674368 1151728560 12.326 99 60 120 1 1512047744 345513872 14805312
09:04:39 4613 8192000000 10435060640 10892517376 1160796544 12.411 100 52 102 0 1537010176 363492384 12721248
09:05:45 12959 8192000000 10438495952 10862407680 1173245184 12.527 100 50 96 1 1538551216 353019264 12286768
09:07:09 3746 8192000000 10433776672 10858778624 1156032800 12.366 100 61 122 0 1531694896 361299936 14362160
09:08:17 12914 8192000000 10380907760 10887360512 1166593984 12.465 100 37 74 0 1479291616 303033152 9664480
09:09:36 42265 8192000000 10304820400 10757943296 1127436064 12.097 99 18 36 1 1417636336 285330480 4869792
09:11:22 1281 8192000000 10201795696 10642886656 1134300384 12.162 99 89 174 3 1294246512 139261344 20684784
09:12:24 16586 8192000000 10211505024 10639286272 1134517296 12.164 99 45 90 0 1305850224 161339232 9993696
09:13:47 62121 8192000000 10144604880 10576064512 1118863296 12.016 99 33 64 3 1245858704 120046256 6949152
09:15:51 68433 8192000000 10074661376 10496049152 1098680448 11.825 97 20 40 1 1143787520 40298176 4808896
09:18:02 43099 8192000000 10092424816 10530062336 1130578416 12.127 100 19 37 1 1198753584 64030624 4144544
09:19:52 162354 8192000000 10071211744 10572967936 1123670656 12.062 98 14 28 0 1192501216 65980416 2850144
09:23:35 88783 8192000000 10056205616 10519187456 1092716976 11.768 96 27 52 0 1118511504 19779920 6014608
09:26:06 55866 8192000000 10078135968 10536144896 1126296944 12.086 99 19 39 2 1168282320 38069152 3916224
09:28:07 20610 8192000000 10146265440 10595483648 1140104112 12.217 99 51 103 0 1247755792 97065056 10586624
09:29:31 20092 8192000000 10153935008 10595414016 1147747680 12.288 100 56 106 1 1252015840 91570928 12697232
09:30:54 2856 8192000000 10149720656 10628960256 1157654896 12.381 100 64 113 2 1249704656 79725760 12324000
09:32:14 104514 8192000000 10126735904 10593734656 1155466256 12.361 100 10 20 0 1215398480 57478784 2453440
09:34:59 85454 8192000000 10120135520 10571898880 1109025472 11.923 96 21 41 0 1176717488 63492800 4199216
09:37:29 51920 8192000000 10068260704 10583318528 1133520896 12.155 99 12 24 2 1221245504 84759456 2965152
09:39:25 18086 8192000000 10096875984 10548674560 1121934016 12.045 98 43 78 2 1177548000 47387008 8226976
09:40:44 29182 8192000000 10121313248 10578780160 1130556400 12.127 99 45 88 0 1208542464 68965312 9020752
09:42:13 44306 8192000000 10107111536 10589503488 1134741728 12.166 99 19 38 0 1198277392 59543504 3992160
09:43:58 48240 8192000000 10093428352 10537963520 1114214784 11.972 98 23 46 0 1170060704 51311040 4534880
09:45:46 80976 8192000000 10112878688 10564050944 1117426464 12.003 98 28 54 0 1201508400 78796032 5285904
09:48:11 36550 8192000000 10102332576 10534420480 1117460448 12.003 99 33 61 0 1171385888 48302816 5622624
09:49:50 97263 8192000000 10098767440 10534379520 1092614112 11.768 96 28 56 2 1126678544 28170752 5893680
09:52:43 81891 8192000000 10082648416 10548600832 1113278176 11.963 98 20 40 1 1181537568 64219568 4039824
09:55:09 1870 8192000000 10118637680 10545680384 1139064560 12.207 100 63 116 3 1213948096 62839600 12043936
09:56:11 49003 8192000000 10148793056 10596814848 1130632192 12.127 99 30 61 0 1258059056 121034208 6392656
09:58:01 71083 8192000000 10105674176 10641674240 1085190912 11.697 96 16 32 3 1212626992 123737216 3698864
10:00:23 49676 8192000000 10090216352 10595799040 1105632560 11.891 99 9 18 1 1188995440 81276672 2086208
10:03:25 57882 8192000000 10067849408 10516889600 1079117184 11.639 96 14 28 0 1131772896 49056608 3599104
10:05:25 53810 8192000000 10128388048 10566631424 1135665808 12.175 100 34 68 1 1209426560 66439616 7321136
10:07:20 50473 8192000000 10157353072 10620252160 1127354240 12.096 99 27 53 0 1244759232 111669104 5735888
10:09:11 85199 8192000000 10110880944 10645704704 1119219904 12.020 98 18 36 2 1232020416 108912704 3887808
10:12:06 11847 8192000000 10068805888 10486157312 1110176560 11.934 99 64 122 1 1148332480 25798272 12357648
10:13:19 23902 8192000000 10107612752 10537918464 1123592368 12.061 100 51 102 0 1198192976 64704400 9896208
10:14:46 97581 8192000000 10065395296 10513432576 1105492944 11.890 98 33 63 0 1169859824 57618896 6747984
10:17:38 85990 8192000000 10068788608 10531110912 1125033552 12.075 99 18 36 0 1167115792 38128128 3954112
10:20:04 72056 8192000000 10088734448 10568900608 1123810368 12.063 99 14 27 1 1168933968 41833696 3289904
10:22:19 92186 8192000000 10085609040 10634076160 1146615792 12.278 100 15 30 2 1246046304 95846288 3584224
10:24:52 67519 8192000000 10079670480 10542788608 1129222992 12.114 99 15 30 0 1156955216 24745072 2987152
10:27:00 91235 8192000000 10063868000 10504073216 1107809712 11.912 97 10 20 0 1180077616 70368464 1899440
10:29:34 30101 8192000000 10092048832 10540584960 1145412736 12.266 100 29 58 0 1194511344 43156432 5942176
10:31:12 94337 8192000000 10076381168 10535370752 1124983168 12.074 98 25 50 0 1179771232 49804240 4983824
10:33:48 45891 8192000000 10135704896 10598359040 1143317248 12.247 99 43 86 1 1243183088 90782912 9082928
10:35:35 57280 8192000000 10154742816 10628042752 1120648496 12.033 98 44 84 0 1237741840 107917360 9175984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-05-2022 10:38:37 to 28-05-2022 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 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 28-05-2022 10:38:54 to 28-05-2022 10:38:56.

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          223989
MON$OLDEST_ACTIVE               227478
MON$OLDEST_SNAPSHOT             227478
MON$NEXT_TRANSACTION            227478
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                       2093376
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Sat May 28 10:37:17 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Sat May 28 10:37:29 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
--- end of diff output ---

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

28.05.22 10:38:57. 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-05-28 10:39:00 No severe PSQL-related problems occured
28.05.22 10:39:01. End of report.