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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16588036 kB
MemAvailable 31366496 kB
Buffers 0 kB
Cached 15140420 kB
SwapCached 1200 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 2022-01-05 13:37:11
DTS_END                         2022-01-05 13:37:11.4100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38402
TRN_ID                          221714
ATT_ID                          645
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: 8922.66 29618 1070719
customer order: creation 168.49 455 20219
customer order: refuse 14.98 372 1798
order to supplier: creation 46.08 1358 5530
order to supplier: removal 3.33 195083 400
invoice (draft): creation 44.91 3045 5389
invoice (draft): removal 49.36 1092 5923
invoice accept: apply 41.13 27057 4936
invoice accept: cancel 44.50 1310 5340
customer reserve: creation 3810.68 260 457281
customer reserve: removal 4408.77 118 529053
realization accept: apply 80.20 253 9624
realization accept: cancel 80.69 176 9683
payment from customer: creation 58.68 171 7041
payment from customer: removal 5.70 111 684
payment to supplier: creation 48.80 178 5856
payment to supplier: removal 3.61 107 433
service: total inventory turnovers 4.28 3475 513
service: total monetary turnovers 7.44 295 893
service: aggregate perf. data 0.99 13779 119
service: refresh index statistics 0.03 343655 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-01-2022 13:39:29 to 05-01-2022 13:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12166
TEST_TIME 2 7606
TEST_TIME 3 6943
TEST_TIME 4 7032
TEST_TIME 5 6705
TEST_TIME 6 6340
TEST_TIME 7 6238
TEST_TIME 8 6625
TEST_TIME 9 6686
TEST_TIME 10 6514
TEST_TIME 11 6598
TEST_TIME 12 6723
TEST_TIME 13 6683
TEST_TIME 14 6559
TEST_TIME 15 6558
TEST_TIME 16 6759
TEST_TIME 17 6706
TEST_TIME 18 6711
TEST_TIME 19 6844
TEST_TIME 20 6886
TEST_TIME 21 7042
TEST_TIME 22 7227
TEST_TIME 23 7225
TEST_TIME 24 7509
TEST_TIME 25 8038
TEST_TIME 26 8252
TEST_TIME 27 9238
TEST_TIME 28 9761
TEST_TIME 29 10495
TEST_TIME 30 11066
TEST_TIME 31 11039
TEST_TIME 32 10808
TEST_TIME 33 10737
TEST_TIME 34 10494
TEST_TIME 35 10342
TEST_TIME 36 10247
TEST_TIME 37 10101
TEST_TIME 38 10063
TEST_TIME 39 9895
TEST_TIME 40 9968
TEST_TIME 41 9897
TEST_TIME 42 9886
TEST_TIME 43 9853
TEST_TIME 44 9720
TEST_TIME 45 9775
TEST_TIME 46 9720
TEST_TIME 47 9683
TEST_TIME 48 9731
TEST_TIME 49 9629
TEST_TIME 50 9689
TEST_TIME 51 9693
TEST_TIME 52 9664
TEST_TIME 53 9737
TEST_TIME 54 9713
TEST_TIME 55 9911
TEST_TIME 56 9896
TEST_TIME 57 9799
TEST_TIME 58 9690
TEST_TIME 59 9659
TEST_TIME 60 9563
TEST_TIME 61 9521
TEST_TIME 62 9433
TEST_TIME 63 9424
TEST_TIME 64 9321
TEST_TIME 65 9309
TEST_TIME 66 9360
TEST_TIME 67 9352
TEST_TIME 68 9356
TEST_TIME 69 9427
TEST_TIME 70 9415
TEST_TIME 71 9439
TEST_TIME 72 9431
TEST_TIME 73 9462
TEST_TIME 74 9449
TEST_TIME 75 9521
TEST_TIME 76 9514
TEST_TIME 77 9489
TEST_TIME 78 9431
TEST_TIME 79 9365
TEST_TIME 80 9342
TEST_TIME 81 9336
TEST_TIME 82 9293
TEST_TIME 83 9247
TEST_TIME 84 9245
TEST_TIME 85 9196
TEST_TIME 86 9197
TEST_TIME 87 9175
TEST_TIME 88 9160
TEST_TIME 89 9140
TEST_TIME 90 9128
TEST_TIME 91 9120
TEST_TIME 92 9144
TEST_TIME 93 9191
TEST_TIME 94 9224
TEST_TIME 95 9439
TEST_TIME 96 9455
TEST_TIME 97 9444
TEST_TIME 98 9422
TEST_TIME 99 9370
TEST_TIME 100 9315
TEST_TIME 101 9260
TEST_TIME 102 9202
TEST_TIME 103 9150
TEST_TIME 104 9103
TEST_TIME 105 9091
TEST_TIME 106 9043
TEST_TIME 107 9004
TEST_TIME 108 9017
TEST_TIME 109 8979
TEST_TIME 110 8953
TEST_TIME 111 8940
TEST_TIME 112 8904
TEST_TIME 113 8988
TEST_TIME 114 9073
TEST_TIME 115 9067
TEST_TIME 116 9032
TEST_TIME 117 9032
TEST_TIME 118 9064
TEST_TIME 119 9024
TEST_TIME 120 8950

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2022 13:39:33 to 05-01-2022 13:39:36.

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 1493120 1493120 0 0.00 0 25795 14 0 0
doc_list_biud 1493105 1493105 0 0.00 0 23116 47 0 0
sp_add_invoice_to_stock 5063 4936 127 2.50 66 200747 27058 0 127
sp_cancel_adding_invoice 5340 5340 0 0.00 6 107932 1310 0 0
sp_cancel_client_order 1798 1798 0 0.00 5 11469 373 0 0
sp_cancel_customer_reserve 529053 529053 0 0.00 1 23124 119 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 7208 112 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2209 107 0 0
sp_cancel_supplier_invoice 5937 5923 14 0.23 2 23041 1093 0 14
sp_cancel_supplier_order 400 400 0 0.00 11 1426127 195083 0 0
sp_cancel_write_off 9683 9683 0 0.00 2 7939 176 0 0
sp_client_order 20219 20219 0 0.00 6 50895 455 0 0
sp_customer_reserve 457281 457281 0 0.00 0 71575 261 0 0
sp_fill_shopping_cart 31133 31133 0 0.00 1 13680 74 0 0
sp_fill_shopping_cart_clo_res 457243 457243 0 0.00 0 21156 21 0 0
sp_get_clo_for_invoice 4890 4890 0 0.00 0 32402 302 0 0
sp_kill_cost_storno 16140 16140 0 0.00 0 25786 69 0 0
sp_kill_qstorno_ret_qs2qd 1074112 1074112 0 0.00 0 22999 52 0 0
sp_kill_qty_storno 566711 566711 0 0.00 0 23112 115 0 0
sp_lock_selected_doc 20363 20363 0 0.00 0 11056 10 0 0
sp_make_cost_storno 27411 27411 0 0.00 0 19081 70 0 0
sp_multiply_rows_for_pdistr 27410 27410 0 0.00 0 24837 37 0 0
sp_multiply_rows_for_qdistr 20219 20219 0 0.00 0 50871 195 0 0
sp_pay_from_customer 7041 7041 0 0.00 5 24848 172 0 0
sp_pay_to_supplier 5856 5856 0 0.00 6 15026 178 0 0
sp_payment_common 12896 12896 0 0.00 2 8410 42 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 1 8901 303 0 0
sp_qd_handle_on_invoice_upd_sts 10230 10230 0 0.00 0 18420 480 0 0
sp_qd_handle_on_reserve_upd_sts 9624 9624 0 0.00 0 19297 57 0 0
sp_reserve_write_off 9624 9624 0 0.00 8 19331 253 0 0
sp_supplier_invoice 5389 5389 0 0.00 9 81642 3045 0 0
sp_supplier_order 5530 5530 0 0.00 7 49506 1358 0 0
srv_aggregate_perf_data 119 119 0 0.00 2494 123634 13780 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 814 99888 30324 0 0
srv_find_qd_qs_mism 1022923 1022923 0 0.00 0 19872 12 0 0
srv_make_invnt_saldo 513 513 0 0.00 76 88060 3475 0 0
srv_make_money_saldo 893 893 0 0.00 1 8973 295 0 0
srv_recalc_idx_stat 4 4 0 0.00 118628 482085 343656 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4942 4942 0 0.00 1 13091 800 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 8966 514 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 792 20 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1357 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 210 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 755 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1586 33 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 82367 6301 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2304 17 0 0
t$perf-norm:sp_client_order 20220 20220 0 0.00 0 13177 35 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 5145 23 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 5369 17 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 6085 21 0 0
t$perf-norm:sp_reserve_write_off 9624 9624 0 0.00 0 11300 21 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 10270 29 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 11424 35 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 1989 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 2054 47 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 4212 18 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 687 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 283 82 0 0
v_add_invoice_to_stock 10022 9768 254 2.53 0 7957 24 0 254
v_all_customers 37188 37188 0 0.00 0 68 3 0 0
v_all_suppliers 30642 30642 0 0.00 0 72 4 0 0
v_all_wares 40438 40438 0 0.00 0 50 3 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1744 15 0 0
v_cancel_client_order 3596 3596 0 0.00 0 1903 10 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 7201 17 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 959 10 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2121 14 0 0
v_cancel_supplier_invoice 822 794 28 3.40 0 2852 28 0 28
v_cancel_supplier_order 800 800 0 0.00 0 470 10 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1913 23 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 3256 6 0 0
v_max_id_clo_res 999 999 0 0.00 0 3370 17 0 0
v_max_id_ord_sup 5385 5385 0 0.00 0 4333 7 0 0
v_max_non_paid_invoice 1449 1449 0 0.00 0 4055 9 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 1295 7 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 3733 10 0 0
v_min_id_clo_res 999 999 0 0.00 0 2282 15 0 0
v_min_id_ord_sup 5385 5385 0 0.00 0 3671 7 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 8403 20 0 0
v_min_non_paid_realizn 7040 7040 0 0.00 0 6208 13 0 0
v_our_firm 10000 10000 0 0.00 0 155 4 0 0
v_reserve_write_off 19248 19248 0 0.00 0 5339 11 0 0
x_lock_depdocs_on_canc_invoice 10866 10866 0 0.00 0 19053 128 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 2887 318 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 3 49490 1211 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 5 80258 2831 0 0
x_make_qstorno_2100_3300 457281 457281 0 0.00 0 71566 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 13:39:37 to 05-01-2022 13:39:41.

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:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 37436 8192000000 9735713056 10033045504 807292384 8.970 100 90 180 0 837014192 7910592 21811216
11:09:57 23248 8192000000 9769692928 10075549696 841720752 9.317 100 89 178 0 876994112 14095328 21178032
11:11:22 21491 8192000000 9802325392 10125660160 869487696 9.595 100 81 162 0 914534288 25740896 19305696
11:12:52 10384 8192000000 9874860928 10210033664 923054432 10.126 100 80 160 0 979985920 39283408 17648080
11:14:07 5190 8192000000 9930582688 10276278272 973021632 10.616 100 89 175 0 1051479408 60241232 18216544
11:15:15 7634 8192000000 9964559024 10324664320 1005943360 10.936 100 80 155 1 1090028528 68676608 15408560
11:16:26 1201 8192000000 10009553920 10371690496 1038195712 11.247 100 98 191 1 1130244208 72916192 19132304
11:17:28 1082 8192000000 10025487664 10397466624 1056232384 11.420 100 90 174 0 1141350032 66399840 18717808
11:18:31 1030 8192000000 10038886080 10415718400 1061035168 11.466 100 95 183 3 1163789728 83855696 18898864
11:19:33 1061 8192000000 10031574016 10407452672 1071081280 11.562 100 94 185 1 1145437024 55349008 19006736
11:20:41 1244 8192000000 10047943680 10428665856 1076040400 11.610 100 96 189 1 1166758240 72067712 18650128
11:21:43 1451 8192000000 10049467920 10433929216 1079977552 11.647 100 98 190 0 1171204512 72282144 18944816
11:22:46 3842 8192000000 10042530352 10432868352 1086112080 11.706 100 64 128 0 1169147328 70523872 12511376
11:23:50 1358 8192000000 10077888896 10464395264 1093841888 11.779 100 96 190 1 1197307488 84780224 18685376
11:24:53 2746 8192000000 10067164192 10448785408 1094977536 11.790 100 94 179 1 1185296048 72173840 18144672
11:25:56 9478 8192000000 10066922672 10464112640 1099084016 11.829 100 54 104 2 1182025024 71652912 11288096
11:27:06 1867 8192000000 10055658112 10449133568 1103510048 11.871 100 81 154 3 1172483104 53468784 15504272
11:28:09 1436 8192000000 10083409936 10467635200 1094574528 11.786 100 98 191 2 1208137904 93415744 20147632
11:29:11 2549 8192000000 10056829904 10438787072 1081361968 11.660 100 75 143 2 1171702512 77097728 13242816
11:30:14 909 8192000000 10024456608 10410938368 1086943136 11.714 100 90 172 1 1152518800 46309632 19266032
11:31:16 1159 8192000000 10005886736 10389942272 1064004736 11.495 99 82 151 0 1125779728 44752464 17022528
11:32:18 3204 8192000000 10024269056 10414800896 1062822640 11.483 100 65 118 1 1148244560 74417088 11004832
11:33:24 1252 8192000000 10012033888 10385113088 1043234432 11.296 99 93 173 2 1133304576 72181952 17888192
11:34:26 1010 8192000000 10018858352 10398085120 1059685520 11.453 100 95 178 2 1138486864 60657856 18143488
11:35:28 1425 8192000000 9983212160 10356563968 1031939696 11.187 99 96 180 3 1105576208 55529184 18107328
11:36:30 1008 8192000000 10002006064 10372497408 1028148288 11.151 99 85 163 1 1117478880 72226576 17104016
11:37:32 917 8192000000 9999510352 10370002944 1042596960 11.290 100 95 181 3 1117245472 56170320 18478192
11:38:42 34648 8192000000 10024799280 10411565056 1056957168 11.427 99 62 124 1 1126425376 57082736 12385472
11:40:31 1076 8192000000 10049405824 10425540608 1085380672 11.699 100 80 151 1 1161385968 59918624 16086672
11:41:35 3562 8192000000 10084754704 10475388928 1087966672 11.723 100 67 132 0 1206991024 105269056 13755296
11:42:46 4556 8192000000 10112479600 10508398592 1107308800 11.907 100 58 116 0 1225870400 106010640 12550960
11:43:53 1155 8192000000 10169979584 10553430016 1120208944 12.029 100 91 174 2 1285257344 144865728 20182672
11:45:02 1782 8192000000 10197129360 10601459712 1129427008 12.116 100 86 166 0 1313413088 165922128 18063952
11:46:06 1315 8192000000 10226814304 10626564096 1152687760 12.335 100 92 180 0 1341690720 169506688 19496272
11:47:12 2564 8192000000 10260580000 10654777344 1159531264 12.399 100 83 164 0 1375282432 197085840 18665328
11:48:18 7288 8192000000 10268597808 10681999360 1173704832 12.531 100 71 141 2 1392790512 203214256 15871424
11:49:28 817 8192000000 10314287904 10717732864 1174569408 12.540 100 96 188 0 1425125712 229596352 20959952
11:50:32 898 8192000000 10382548272 10779000832 1194571648 12.726 100 96 184 0 1489939712 274251632 21116432
11:51:38 2286 8192000000 10443971920 10846789632 1210276304 12.872 100 88 171 0 1551342768 321001136 20065328
11:52:45 3278 8192000000 10467951184 10880466944 1211102192 12.879 100 91 179 1 1578246960 345988528 21156240
11:53:53 1451 8192000000 10517056880 10918854656 1215635056 12.921 100 93 185 1 1626109856 388909392 21565408
11:54:58 1194 8192000000 10558658944 10975137792 1221171312 12.973 100 97 187 0 1669098560 424396848 23530400
11:56:00 885 8192000000 10622845264 11055742976 1221606048 12.977 100 99 197 1 1727771984 482654448 23511488
11:57:09 862 8192000000 10665384640 11071676416 1224415488 13.002 100 93 181 1 1771370144 525505616 21449040
11:58:12 832 8192000000 10667896944 11083493376 1202888336 12.803 100 98 189 0 1773022784 546381600 23752848
11:59:15 809 8192000000 10685585168 11132104704 1193811088 12.719 100 83 163 0 1791825824 576982976 21031760
12:00:24 1894 8192000000 10714751392 11144192000 1198171872 12.759 100 89 176 0 1819987792 599674768 22141152
12:01:29 981 8192000000 10673854576 11163230208 1187558432 12.661 100 82 162 2 1779101360 571090816 20452112
12:02:36 9959 8192000000 10638891344 11093712896 1184328800 12.631 100 49 98 0 1731449600 533675712 13445088
12:03:54 26942 8192000000 10498801984 11011686400 1173544688 12.530 100 42 84 0 1658919232 473582928 11791616
12:06:21 41407 8192000000 10170067664 10745241600 1143876928 12.252 99 13 26 2 1358321616 211425024 3019664
12:08:09 50680 8192000000 10061042848 10521530368 1146307984 12.275 100 10 20 0 1196623712 48325136 1990592
12:10:00 64466 8192000000 10106198448 10575364096 1128550160 12.108 99 46 92 0 1196446112 58461168 9434784
12:12:05 73638 8192000000 10074948560 10506350592 1135045168 12.169 99 37 73 1 1185106416 42328752 7732496
12:14:47 29860 8192000000 10112953744 10555932672 1145174816 12.264 99 31 62 0 1205272832 53584208 6513808
12:16:17 11794 8192000000 10156877968 10587381760 1160335120 12.406 100 59 112 4 1249332464 77557712 11439632
12:17:33 42670 8192000000 10162396864 10649321472 1163868720 12.439 100 22 44 0 1294236944 125572400 4795824
12:19:15 30496 8192000000 10141849280 10629980160 1157317312 12.378 100 15 30 1 1246841824 86155968 3368544
12:21:44 7717 8192000000 10110088560 10567753728 1152939280 12.337 100 26 51 1 1207213920 48512048 5762592
12:22:52 34380 8192000000 10063644944 10527440896 1084172064 11.687 94 20 39 2 1129091376 40617328 4301984
12:24:37 42013 8192000000 10123732592 10613075968 1142708032 12.241 99 26 50 1 1224998032 76529504 5760496
12:26:29 49342 8192000000 10156979104 10616983552 1145675792 12.269 99 31 59 1 1247662560 95533552 6453216
12:28:19 28504 8192000000 10111972816 10570543104 1112057152 11.952 97 29 57 1 1202934096 85254256 5622688
12:29:53 31432 8192000000 10190246624 10630541312 1136768976 12.185 99 30 60 0 1252940992 109801056 6370960
12:31:25 69060 8192000000 10156930288 10633940992 1111445088 11.946 97 19 38 0 1209933072 94440816 4047168
12:33:35 53126 8192000000 10048884288 10464178176 1076713952 11.616 95 35 62 2 1102937296 19872592 6350752
12:35:29 45050 8192000000 10078997616 10515005440 1126099760 12.085 100 56 109 0 1189571856 52190128 11281968
12:37:19 27369 8192000000 10106613744 10552885248 1134659264 12.165 100 49 98 0 1203426368 59172240 9594864
12:38:49 57393 8192000000 10100268304 10560204800 1133319760 12.153 99 17 34 0 1196098016 58910224 3868032
12:40:47 66696 8192000000 10122122240 10565742592 1107236016 11.906 97 15 30 2 1186082528 75486496 3360016
12:43:16 66977 8192000000 10112605744 10577502208 1102577792 11.862 97 30 59 0 1204189152 95094752 6516608
12:45:23 31380 8192000000 10109821616 10553573376 1093930128 11.780 97 36 71 1 1168791456 67337520 7523808
12:46:56 10272 8192000000 10081711408 10500784128 1116846112 11.997 99 60 120 0 1183363504 54471360 12046032
12:48:06 18906 8192000000 10124794096 10570752000 1106367104 11.898 98 42 84 0 1192812704 77561760 8883840
12:49:34 8403 8192000000 10068908960 10491211776 1098087360 11.819 98 47 88 1 1151780784 44807680 8885744
12:50:47 3245 8192000000 10093340832 10504089600 1113814096 11.969 100 64 128 0 1179575888 52730496 13031296
12:51:54 18162 8192000000 10151783408 10579746816 1134968112 12.168 100 52 101 0 1235880544 91197664 9714768
12:53:16 22173 8192000000 10085219808 10499649536 1130970688 12.131 100 58 108 0 1179466096 37453600 11041808
12:54:39 83254 8192000000 10081015488 10544209920 1127201296 12.095 100 61 116 0 1204049344 64279968 12568080
12:57:07 11416 8192000000 10095584768 10540478464 1127311584 12.096 100 47 92 2 1200264192 63974032 8978576
12:58:22 57334 8192000000 10094996208 10553757696 1136082704 12.179 100 15 30 0 1197962768 58635216 3244848
13:00:34 94106 8192000000 10059105472 10516520960 1106499792 11.899 97 9 18 0 1177686048 69113360 2072896
13:03:11 23113 8192000000 10105982800 10569310208 1129867472 12.120 99 33 66 0 1200963536 63794880 7301184
13:04:35 26346 8192000000 10167660496 10608873472 1159190672 12.396 100 27 54 3 1257640672 92280176 6169824
13:06:03 19203 8192000000 10172333360 10611732480 1159330592 12.397 100 31 62 0 1275431056 109047440 7053024
13:07:25 22637 8192000000 10205532432 10646376448 1142543552 12.239 98 47 94 3 1296676112 143344512 10788048
13:08:49 45053 8192000000 10261426944 10727469056 1164083264 12.441 100 30 60 1 1362114528 190192080 7839184
13:10:39 44901 8192000000 10279946352 10758119424 1163043776 12.432 100 24 48 0 1389347440 220571264 5732400
13:12:27 82908 8192000000 10111609456 10628509696 1115829680 11.988 97 17 34 1 1211122544 92051232 3241632
13:14:50 99887 8192000000 10078980368 10512916480 1093965872 11.780 96 23 43 3 1131803360 33417312 4420176
13:17:54 66146 8192000000 10059023472 10526498816 1109030880 11.923 97 23 46 0 1149771760 36570416 4170464
13:20:14 31016 8192000000 10058869792 10528571392 1128916720 12.111 100 12 22 1 1161077696 30005424 2155552
13:21:45 65191 8192000000 10068315184 10557677568 1130333856 12.125 100 17 34 0 1189323392 55614880 3374656
13:23:57 44859 8192000000 10125931200 10569433088 1149277504 12.303 100 30 61 2 1206295744 50833024 6185216
13:25:44 35764 8192000000 10122829440 10570493952 1139242448 12.208 98 21 42 0 1202406064 58470080 4693536
13:27:21 27109 8192000000 10172058752 10613108736 1145306064 12.265 99 36 72 0 1261789248 107816368 8666816
13:28:55 48816 8192000000 10239492848 10681843712 1150170656 12.311 99 36 72 1 1320600720 162288048 8142016
13:30:49 74295 8192000000 10086859232 10552246272 1125890160 12.083 98 19 36 1 1216275760 86458368 3927232
13:33:04 13109 8192000000 10073084960 10504806400 1110998016 11.942 98 44 88 1 1139407008 19450576 8958416
13:34:17 13662 8192000000 10098056784 10516676608 1115510944 11.985 99 57 114 0 1173572704 46825056 11236704
13:35:33 95317 8192000000 10071563888 10512797696 1059465888 11.451 93 23 46 0 1102898704 38634912 4797904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 05-01-2022 13:39:43 to 05-01-2022 13:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 13:40:01 to 05-01-2022 13:40:03.

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          220727
MON$OLDEST_ACTIVE               221897
MON$OLDEST_SNAPSHOT             221897
MON$NEXT_TRANSACTION            221897
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                       2027872
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.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Wed Jan  5 13:37:53 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Wed Jan  5 13:38:01 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

05.01.22 13:40:04. 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-01-05 13:40:06 No severe PSQL-related problems occured
05.01.22 13:40:07. End of report.