Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2021 13: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 16360340 kB
MemAvailable 31508456 kB
Buffers 0 kB
Cached 15351764 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-11 13:37:10
DTS_END                         2021-12-11 13:37:10.5680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(301:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42442
TRN_ID                          227182
ATT_ID                          601
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: 9384.58 20334 1126150
customer order: creation 163.71 415 19645
customer order: refuse 14.19 316 1703
order to supplier: creation 43.49 1405 5219
order to supplier: removal 3.48 127857 418
invoice (draft): creation 43.10 3482 5172
invoice (draft): removal 54.72 1096 6566
invoice accept: apply 39.27 18137 4712
invoice accept: cancel 50.18 874 6022
customer reserve: creation 3741.28 174 448953
customer reserve: removal 4944.23 70 593308
realization accept: apply 78.87 180 9464
realization accept: cancel 86.68 118 10401
payment from customer: creation 55.24 140 6629
payment from customer: removal 5.18 91 622
payment to supplier: creation 46.91 131 5629
payment to supplier: removal 3.38 98 405
service: total inventory turnovers 3.60 3615 432
service: total monetary turnovers 6.23 478 747
service: aggregate perf. data 0.83 21291 99
service: refresh index statistics 0.03 226715 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-12-2021 13:38:44 to 11-12-2021 13:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5631
TEST_TIME 2 6634
TEST_TIME 3 5645
TEST_TIME 4 5283
TEST_TIME 5 5153
TEST_TIME 6 5279
TEST_TIME 7 5491
TEST_TIME 8 5685
TEST_TIME 9 5591
TEST_TIME 10 5834
TEST_TIME 11 6135
TEST_TIME 12 6156
TEST_TIME 13 6342
TEST_TIME 14 6307
TEST_TIME 15 6329
TEST_TIME 16 6432
TEST_TIME 17 6680
TEST_TIME 18 7550
TEST_TIME 19 7800
TEST_TIME 20 7963
TEST_TIME 21 7979
TEST_TIME 22 7932
TEST_TIME 23 8202
TEST_TIME 24 8572
TEST_TIME 25 9741
TEST_TIME 26 11065
TEST_TIME 27 11083
TEST_TIME 28 11257
TEST_TIME 29 11126
TEST_TIME 30 10982
TEST_TIME 31 10972
TEST_TIME 32 11001
TEST_TIME 33 10953
TEST_TIME 34 10853
TEST_TIME 35 10816
TEST_TIME 36 10838
TEST_TIME 37 10917
TEST_TIME 38 10897
TEST_TIME 39 10898
TEST_TIME 40 11001
TEST_TIME 41 10791
TEST_TIME 42 10634
TEST_TIME 43 10565
TEST_TIME 44 10384
TEST_TIME 45 10350
TEST_TIME 46 10236
TEST_TIME 47 10203
TEST_TIME 48 10291
TEST_TIME 49 10269
TEST_TIME 50 10123
TEST_TIME 51 10040
TEST_TIME 52 10111
TEST_TIME 53 10091
TEST_TIME 54 10132
TEST_TIME 55 10129
TEST_TIME 56 10158
TEST_TIME 57 10168
TEST_TIME 58 10159
TEST_TIME 59 10156
TEST_TIME 60 10221
TEST_TIME 61 10185
TEST_TIME 62 10283
TEST_TIME 63 10279
TEST_TIME 64 10242
TEST_TIME 65 10322
TEST_TIME 66 10334
TEST_TIME 67 10283
TEST_TIME 68 10150
TEST_TIME 69 10176
TEST_TIME 70 10087
TEST_TIME 71 10013
TEST_TIME 72 10041
TEST_TIME 73 10002
TEST_TIME 74 9968
TEST_TIME 75 9934
TEST_TIME 76 9925
TEST_TIME 77 9883
TEST_TIME 78 9819
TEST_TIME 79 9786
TEST_TIME 80 9746
TEST_TIME 81 9794
TEST_TIME 82 9779
TEST_TIME 83 9749
TEST_TIME 84 9763
TEST_TIME 85 9795
TEST_TIME 86 9793
TEST_TIME 87 9883
TEST_TIME 88 9861
TEST_TIME 89 9874
TEST_TIME 90 9856
TEST_TIME 91 9899
TEST_TIME 92 9806
TEST_TIME 93 9775
TEST_TIME 94 9753
TEST_TIME 95 9700
TEST_TIME 96 9634
TEST_TIME 97 9587
TEST_TIME 98 9574
TEST_TIME 99 9551
TEST_TIME 100 9529
TEST_TIME 101 9498
TEST_TIME 102 9484
TEST_TIME 103 9521
TEST_TIME 104 9522
TEST_TIME 105 9533
TEST_TIME 106 9600
TEST_TIME 107 9587
TEST_TIME 108 9623
TEST_TIME 109 9683
TEST_TIME 110 9665
TEST_TIME 111 9673
TEST_TIME 112 9616
TEST_TIME 113 9589
TEST_TIME 114 9575
TEST_TIME 115 9570
TEST_TIME 116 9520
TEST_TIME 117 9495
TEST_TIME 118 9453
TEST_TIME 119 9456
TEST_TIME 120 9385

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2021 13:38:48 to 11-12-2021 13:38:51.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1542206 1542206 0 0.00 0 61606 7 0 0
doc_list_biud 1542206 1542206 0 0.00 0 35081 34 0 0
sp_add_invoice_to_stock 4828 4712 116 2.40 53 165869 18138 0 116
sp_cancel_adding_invoice 6022 6022 0 0.00 5 62704 875 0 0
sp_cancel_client_order 1703 1703 0 0.00 4 10853 317 0 0
sp_cancel_customer_reserve 593308 593308 0 0.00 1 26377 70 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 8101 91 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 7030 98 0 0
sp_cancel_supplier_invoice 6572 6566 6 0.09 2 35087 1097 0 6
sp_cancel_supplier_order 418 418 0 0.00 7 1263840 127857 0 0
sp_cancel_write_off 10401 10401 0 0.00 1 11902 119 0 0
sp_client_order 19645 19645 0 0.00 4 22097 415 0 0
sp_customer_reserve 448953 448953 0 0.00 0 40838 174 0 0
sp_fill_shopping_cart 30029 30029 0 0.00 1 18840 39 0 0
sp_fill_shopping_cart_clo_res 448953 448953 0 0.00 0 20556 14 0 0
sp_get_clo_for_invoice 4712 4712 0 0.00 0 28334 310 0 0
sp_kill_cost_storno 17450 17450 0 0.00 0 15959 66 0 0
sp_kill_qstorno_ret_qs2qd 1204001 1204001 0 0.00 0 35079 39 0 0
sp_kill_qty_storno 632594 632594 0 0.00 0 35080 81 0 0
sp_lock_selected_doc 19663 19663 0 0.00 0 9440 4 0 0
sp_make_cost_storno 26434 26434 0 0.00 0 61593 70 0 0
sp_multiply_rows_for_pdistr 26434 26434 0 0.00 0 10430 36 0 0
sp_multiply_rows_for_qdistr 19645 19645 0 0.00 0 14228 204 0 0
sp_pay_from_customer 6629 6629 0 0.00 4 16218 140 0 0
sp_pay_to_supplier 5629 5629 0 0.00 4 24639 132 0 0
sp_payment_common 12258 12258 0 0.00 1 12836 23 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 10847 287 0 0
sp_qd_handle_on_invoice_upd_sts 10734 10734 0 0.00 0 26007 345 0 0
sp_qd_handle_on_reserve_upd_sts 9464 9464 0 0.00 0 8647 48 0 0
sp_reserve_write_off 9464 9464 0 0.00 7 15952 180 0 0
sp_supplier_invoice 5172 5172 0 0.00 5 96665 3483 0 0
sp_supplier_order 5219 5219 0 0.00 5 39163 1405 0 0
srv_aggregate_perf_data 99 99 0 0.00 2991 418478 21291 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2931 130890 46506 0 0
srv_find_qd_qs_mism 1080761 1080761 0 0.00 0 11772 5 0 0
srv_make_invnt_saldo 432 432 0 0.00 35 111765 3616 0 0
srv_make_money_saldo 747 747 0 0.00 1 10045 478 0 0
srv_recalc_idx_stat 4 4 0 0.00 120920 292109 226716 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4713 4713 0 0.00 0 31211 181 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3850 72 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 1019 9 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 1839 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 1913 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 194 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 580 8 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 23089 1694 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 2829 10 0 0
t$perf-norm:sp_client_order 19645 19645 0 0.00 0 15373 18 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1613 8 0 0
t$perf-norm:sp_pay_from_customer 6629 6629 0 0.00 0 15442 15 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 7740 11 0 0
t$perf-norm:sp_reserve_write_off 9464 9464 0 0.00 0 4156 10 0 0
t$perf-norm:sp_supplier_invoice 5172 5172 0 0.00 0 7480 18 0 0
t$perf-norm:sp_supplier_order 5219 5219 0 0.00 0 4582 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 118 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 489 9 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 4932 24 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 6651 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 9656 9424 232 2.40 0 5730 16 0 232
v_all_customers 36072 36072 0 0.00 0 20 1 0 0
v_all_suppliers 29350 29350 0 0.00 0 108 1 0 0
v_all_wares 39290 39290 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1137 9 0 0
v_cancel_client_order 3406 3406 0 0.00 0 2623 4 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 7417 11 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 2978 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 7019 13 0 0
v_cancel_supplier_invoice 728 716 12 1.64 0 1634 15 0 12
v_cancel_supplier_order 836 836 0 0.00 0 2379 8 0 0
v_cancel_write_off 1894 1894 0 0.00 0 1003 12 0 0
v_max_id_clo_ord 5217 5217 0 0.00 0 1944 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 8985 16 0 0
v_max_id_ord_sup 5166 5166 0 0.00 0 4977 4 0 0
v_max_non_paid_invoice 1337 1337 0 0.00 0 118 2 0 0
v_max_non_paid_realizn 3321 3321 0 0.00 0 2084 4 0 0
v_min_id_clo_ord 5217 5217 0 0.00 0 1809 5 0 0
v_min_id_clo_res 990 990 0 0.00 0 2781 8 0 0
v_min_id_ord_sup 5166 5166 0 0.00 0 10021 6 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 6995 15 0 0
v_min_non_paid_realizn 6629 6629 0 0.00 0 7877 18 0 0
v_our_firm 9834 9834 0 0.00 0 105 1 0 0
v_reserve_write_off 18928 18928 0 0.00 0 3259 5 0 0
x_lock_depdocs_on_canc_invoice 12230 12230 0 0.00 0 8090 102 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 30000 369 0 0
x_make_qstorno_1000_1200 5219 5219 0 0.00 2 39158 1331 0 0
x_make_qstorno_1200_2000 5172 5172 0 0.00 3 96654 3337 0 0
x_make_qstorno_2100_3300 448953 448953 0 0.00 0 40837 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2021 13:38:52 to 11-12-2021 13:38:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 24889 8192000000 9723130752 10021167104 800323472 8.900 100 93 186 0 831028336 7384496 23320368
11:09:52 22325 8192000000 9762359136 10068951040 837133440 9.271 100 91 182 0 872312512 13536784 21642288
11:11:24 18501 8192000000 9812892496 10132168704 871693760 9.617 100 83 166 0 918663504 27726112 19243632
11:12:50 6881 8192000000 9877712336 10211799040 927709696 10.172 100 79 158 0 994662816 49383952 17569168
11:13:59 5817 8192000000 9915229136 10262888448 958760880 10.477 100 74 148 0 1032872544 58659456 15452208
11:15:07 3833 8192000000 9956583280 10310361088 1000386576 10.882 100 71 141 1 1071526272 56567584 14572112
11:16:14 4471 8192000000 9986293536 10352656384 1021671248 11.088 100 69 138 1 1098175648 62404336 14100064
11:17:20 2713 8192000000 10015376608 10382008320 1048575216 11.347 100 85 167 2 1118507168 52525488 17406464
11:18:25 1193 8192000000 10036465136 10408394752 1065100368 11.505 100 89 180 1 1142099200 58353152 18645680
11:19:28 2485 8192000000 10042846976 10430386176 1070309008 11.555 100 61 121 1 1160636752 78957920 11369824
11:20:32 5884 8192000000 10009012576 10414522368 1077549872 11.624 100 34 67 1 1117851872 33062736 7239264
11:21:39 25860 8192000000 10020227856 10431221760 1079439024 11.642 100 24 48 0 1121386192 37493760 4453408
11:23:06 7449 8192000000 10023173648 10421313536 1087697200 11.721 100 47 94 1 1133913360 36166176 10049984
11:24:14 7679 8192000000 10024944448 10427031552 1090417920 11.747 100 42 83 1 1133440944 33973040 9049984
11:25:28 64117 8192000000 10010292416 10412670976 1090959920 11.752 100 32 62 1 1123498432 25902848 6635664
11:27:32 6385 8192000000 10021840224 10425597952 1094735792 11.788 100 39 77 0 1137623952 35454240 7433920
11:28:40 10102 8192000000 9975609216 10378698752 1011238080 10.987 93 35 71 1 1039089696 20109504 7742112
11:29:54 5602 8192000000 9942808816 10319945728 982587472 10.709 94 48 96 0 1012420624 18943488 10889664
11:31:04 6050 8192000000 9960253456 10347278336 1025626880 11.126 99 36 72 0 1052426704 19612880 7186944
11:32:14 7558 8192000000 9940415536 10333925376 1032948688 11.197 100 45 89 3 1061588736 18537600 10102448
11:33:23 4554 8192000000 9964120608 10338222080 1045960288 11.322 100 58 114 4 1069502000 10777968 12763744
11:34:28 46513 8192000000 9953391888 10348212224 1054967072 11.408 100 29 58 1 1082986160 21684144 6334944
11:36:16 7840 8192000000 9988795200 10378592256 1066893088 11.522 100 40 78 1 1102296608 27055600 8347920
11:37:28 5749 8192000000 10012542432 10399797248 1093077376 11.772 100 48 97 0 1117257056 13653696 10525984
11:38:37 9636 8192000000 10054894144 10455846912 1106905184 11.903 100 53 106 1 1164187328 46398448 10883696
11:40:02 101947 8192000000 10088374032 10510979072 1126117840 12.085 100 23 46 2 1186924768 55710944 5095984
11:42:48 8625 8192000000 10113290816 10527387648 1124648064 12.071 99 56 114 1 1218568624 81347408 12573152
11:44:03 7669 8192000000 10158250032 10563821568 1149397536 12.304 100 49 98 1 1260578112 100943840 10236736
11:45:17 13934 8192000000 10224802768 10637119488 1169178944 12.489 100 53 105 0 1324953424 144413824 11360656
11:46:39 6022 8192000000 10288042080 10688159744 1189116960 12.675 100 72 142 1 1391658752 186389296 16152496
11:47:47 5460 8192000000 10307826464 10717921280 1199314096 12.770 100 67 132 1 1414645728 200715792 14615840
11:48:56 3973 8192000000 10394603648 10798379008 1199333136 12.770 100 72 144 1 1487156112 271166928 16656048
11:50:06 2931 8192000000 10434947120 10845556736 1212662112 12.894 100 78 157 0 1536727968 306642784 17423072
11:51:17 3510 8192000000 10473900384 10888732672 1199165808 12.769 99 73 146 0 1568889184 352720816 17002560
11:52:25 3602 8192000000 10520107472 10926075904 1201595456 12.791 100 85 170 0 1618829776 397095824 20138496
11:53:32 3643 8192000000 10569725968 10985144320 1205589360 12.828 100 79 159 1 1667367168 442601888 19175920
11:54:41 2945 8192000000 10561079472 10968928256 1179637536 12.587 99 84 165 1 1649158048 449999792 19520720
11:55:47 12324 8192000000 10529303168 10986074112 1192696496 12.708 100 48 95 0 1625145440 420302560 12146384
11:57:03 11905 8192000000 10514202064 10943655936 1164174512 12.442 98 39 76 2 1602725296 428299680 10251104
11:58:27 7701 8192000000 10522869840 10949996544 1162709904 12.429 100 39 79 1 1620478800 447315312 10453584
11:59:58 18099 8192000000 10561942912 11039207424 1139404160 12.210 99 42 84 1 1695091488 544095296 11592032
12:01:53 19063 8192000000 10494456208 10925969408 1072915312 11.580 97 33 65 1 1563188800 480787776 9485712
12:03:14 52116 8192000000 10107999600 10538496000 1126323040 12.087 100 36 72 1 1216415712 82026112 8066560
12:05:08 63086 8192000000 10085898416 10589253632 1112330528 11.954 99 9 17 0 1184089072 69941264 1817280
12:07:13 71762 8192000000 10057922208 10494341120 1084534176 11.691 96 15 30 1 1110782816 22741296 3507344
12:09:26 60600 8192000000 10058370000 10494304256 1089754896 11.740 96 15 30 1 1111052880 18403344 2894640
12:11:39 63358 8192000000 10040289152 10493952000 1080136448 11.649 96 17 34 1 1128906560 45677552 3092560
12:13:49 99807 8192000000 10083409200 10585755648 1071205904 11.564 96 2 4 0 1111385680 39220304 959472
12:16:29 91896 8192000000 10087223136 10576240640 1110094944 11.933 98 9 18 1 1167205744 55177344 1933456
12:19:03 82402 8192000000 10073612800 10492862464 1110370560 11.936 98 38 75 2 1141414064 22585488 8458016
12:21:25 14718 8192000000 10101060656 10522537984 1142512176 12.239 100 50 96 1 1188487056 36075584 9899296
12:22:43 54922 8192000000 10152346880 10601963520 1130771248 12.129 98 32 64 1 1231849760 93393360 7685152
12:24:40 23256 8192000000 10163902160 10597539840 1146480032 12.276 100 31 62 0 1243951168 90784912 6686224
12:26:13 79884 8192000000 10161269424 10641727488 1146225200 12.274 99 15 30 0 1246792848 96811952 3755696
12:28:37 33301 8192000000 10163086816 10617647104 1139505360 12.211 99 17 34 0 1278469536 134782384 4181792
12:30:21 43387 8192000000 10133196480 10585456640 1143622640 12.250 100 10 20 1 1215090560 69016688 2451232
12:32:06 28865 8192000000 10078747264 10544988160 1141917584 12.234 100 25 48 1 1172265088 24232848 6114656
12:33:39 34516 8192000000 10107734032 10581237760 1142850320 12.242 100 17 34 1 1207953024 61420128 3682576
12:35:18 18298 8192000000 10075057408 10508316672 1111058720 11.942 97 42 84 0 1140105056 20157248 8889088
12:36:43 53511 8192000000 10084158800 10567565312 1121999296 12.046 98 28 55 1 1189120736 61339056 5782384
12:38:44 64021 8192000000 10127012160 10605989888 1148963232 12.300 99 19 38 1 1230186864 77340160 3883472
12:42:30 55267 8192000000 10112158032 10584145920 1144553328 12.258 99 16 32 2 1211611376 63727248 3330800
12:44:48 101975 8192000000 10138094288 10588344320 1134776096 12.166 99 22 44 0 1204117440 64517968 4823376
12:47:35 47328 8192000000 10109978976 10577711104 1124536528 12.070 98 17 34 1 1196159216 68029296 3593392
12:49:22 66301 8192000000 10115668016 10584182784 1124883760 12.073 98 14 28 1 1196862416 68959872 3018784
12:52:01 70004 8192000000 10053695632 10528567296 1104738640 11.883 98 13 26 0 1166112656 58322128 3051888
12:54:12 40729 8192000000 10055619424 10530054144 1093567904 11.777 97 12 24 1 1136786592 40105808 3112880
12:55:53 90162 8192000000 10074805200 10559385600 1112006704 11.951 99 14 28 0 1170196400 54723072 3466624
12:58:35 70114 8192000000 10048965424 10482634752 1057284688 11.430 94 15 30 0 1108966816 48669104 3013024
13:00:46 54495 8192000000 10102418608 10530631680 1068581648 11.539 97 40 80 1 1157653376 80550256 8521472
13:02:40 31832 8192000000 10071947568 10505240576 1085961808 11.704 99 27 54 0 1158504416 67354576 5188032
13:04:17 118090 8192000000 10064406272 10513465344 1094227616 11.783 98 19 38 1 1166692384 68561808 3902960
13:07:22 8681 8192000000 10074840576 10502733824 1123265600 12.058 100 58 115 0 1176743184 42002160 11475424
13:08:31 130889 8192000000 10073961360 10509029376 1137259712 12.190 100 24 47 0 1192425280 50706272 4459296
13:11:42 57628 8192000000 10050800912 10516058112 1117461504 12.003 98 13 26 0 1154676352 34763248 2451600
13:13:40 89426 8192000000 10083530768 10545971200 1129122112 12.113 99 15 30 0 1184837488 52302160 3413216
13:16:11 70087 8192000000 10107455136 10574274560 1149486208 12.305 100 25 50 0 1206072080 50466608 6119264
13:18:21 43841 8192000000 10148110912 10592120832 1146183920 12.274 99 28 56 1 1249467760 97100784 6183056
13:20:14 89841 8192000000 10118155696 10605645824 1124391856 12.068 98 19 38 1 1226241056 97365520 4483680
13:22:44 47476 8192000000 10108934400 10577702912 1117602000 12.004 97 23 46 1 1210283616 87747920 4933696
13:24:33 31831 8192000000 10128606592 10569736192 1127442784 12.097 99 40 80 1 1206979520 71405920 8130816
13:26:12 60409 8192000000 10078479184 10552926208 1134675440 12.165 100 9 16 1 1179542656 43543296 1323920
13:28:14 111117 8192000000 10142505040 10620268544 1131514912 12.136 98 21 42 0 1231421680 95280704 4626064
13:31:06 22139 8192000000 10099413984 10566074368 1151942240 12.328 100 24 49 0 1199551360 42575872 5033248
13:32:33 74527 8192000000 10077068432 10544979968 1149734656 12.307 100 10 20 0 1186550816 34362240 2453920
13:35:00 63195 8192000000 10107807408 10585038848 1148754960 12.298 99 18 36 0 1217991312 65557824 3678528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2021 13:38:58 to 11-12-2021 13:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-12-2021 13:39:14 to 11-12-2021 13:39:15.

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          226748
MON$OLDEST_ACTIVE               227379
MON$OLDEST_SNAPSHOT             227379
MON$NEXT_TRANSACTION            227379
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                       2072928
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2682 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2682 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2682 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2682 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Dec 11 13:37:13 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Dec 11 13:37:15 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

11.12.21 13:39:16. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-11 13:39:19 No severe PSQL-related problems occured
11.12.21 13:39:20. End of report.