Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 07:48
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 16094676 kB
MemAvailable 31317404 kB
Buffers 0 kB
Cached 15562640 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 07:47:51
DTS_END                         2022-01-05 07:47:51.8670
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/34644
TRN_ID                          209340
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: 9026.00 25665 1083120
customer order: creation 160.01 508 19201
customer order: refuse 13.61 435 1633
order to supplier: creation 42.33 1498 5080
order to supplier: removal 3.40 200752 408
invoice (draft): creation 43.04 3926 5165
invoice (draft): removal 53.57 1100 6428
invoice accept: apply 38.73 26308 4647
invoice accept: cancel 48.42 1236 5810
customer reserve: creation 3624.61 247 434953
customer reserve: removal 4713.09 112 565571
realization accept: apply 77.25 289 9270
realization accept: cancel 88.80 190 10656
payment from customer: creation 53.98 194 6478
payment from customer: removal 5.23 134 628
payment to supplier: creation 44.94 193 5393
payment to supplier: removal 3.22 170 386
service: total inventory turnovers 3.58 4792 430
service: total monetary turnovers 7.27 384 872
service: aggregate perf. data 0.88 18636 105
service: refresh index statistics 0.05 252197 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 07:48:37 to 05-01-2022 07:48:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2895
TEST_TIME 2 4193
TEST_TIME 3 3944
TEST_TIME 4 4648
TEST_TIME 5 4999
TEST_TIME 6 5141
TEST_TIME 7 5377
TEST_TIME 8 5310
TEST_TIME 9 5335
TEST_TIME 10 5928
TEST_TIME 11 5922
TEST_TIME 12 6108
TEST_TIME 13 6196
TEST_TIME 14 6271
TEST_TIME 15 6724
TEST_TIME 16 6694
TEST_TIME 17 6663
TEST_TIME 18 6655
TEST_TIME 19 6964
TEST_TIME 20 7280
TEST_TIME 21 7517
TEST_TIME 22 7434
TEST_TIME 23 7408
TEST_TIME 24 7532
TEST_TIME 25 7668
TEST_TIME 26 8100
TEST_TIME 27 8392
TEST_TIME 28 8264
TEST_TIME 29 8422
TEST_TIME 30 8510
TEST_TIME 31 8518
TEST_TIME 32 8976
TEST_TIME 33 9230
TEST_TIME 34 9767
TEST_TIME 35 10459
TEST_TIME 36 11018
TEST_TIME 37 11053
TEST_TIME 38 11033
TEST_TIME 39 10873
TEST_TIME 40 10862
TEST_TIME 41 10677
TEST_TIME 42 10714
TEST_TIME 43 10502
TEST_TIME 44 10456
TEST_TIME 45 10440
TEST_TIME 46 10321
TEST_TIME 47 10257
TEST_TIME 48 10142
TEST_TIME 49 10113
TEST_TIME 50 9986
TEST_TIME 51 9919
TEST_TIME 52 9846
TEST_TIME 53 9780
TEST_TIME 54 9661
TEST_TIME 55 9550
TEST_TIME 56 9525
TEST_TIME 57 9479
TEST_TIME 58 9505
TEST_TIME 59 9412
TEST_TIME 60 9413
TEST_TIME 61 9398
TEST_TIME 62 9309
TEST_TIME 63 9211
TEST_TIME 64 9239
TEST_TIME 65 9371
TEST_TIME 66 9353
TEST_TIME 67 9382
TEST_TIME 68 9379
TEST_TIME 69 9431
TEST_TIME 70 9398
TEST_TIME 71 9340
TEST_TIME 72 9327
TEST_TIME 73 9472
TEST_TIME 74 9469
TEST_TIME 75 9466
TEST_TIME 76 9449
TEST_TIME 77 9463
TEST_TIME 78 9369
TEST_TIME 79 9285
TEST_TIME 80 9206
TEST_TIME 81 9170
TEST_TIME 82 9157
TEST_TIME 83 9102
TEST_TIME 84 9057
TEST_TIME 85 9006
TEST_TIME 86 9060
TEST_TIME 87 9074
TEST_TIME 88 9050
TEST_TIME 89 9039
TEST_TIME 90 9020
TEST_TIME 91 9023
TEST_TIME 92 9069
TEST_TIME 93 9056
TEST_TIME 94 9123
TEST_TIME 95 9136
TEST_TIME 96 9172
TEST_TIME 97 9150
TEST_TIME 98 9107
TEST_TIME 99 9259
TEST_TIME 100 9227
TEST_TIME 101 9175
TEST_TIME 102 9122
TEST_TIME 103 9124
TEST_TIME 104 9087
TEST_TIME 105 9070
TEST_TIME 106 9043
TEST_TIME 107 9002
TEST_TIME 108 8988
TEST_TIME 109 8955
TEST_TIME 110 8942
TEST_TIME 111 8899
TEST_TIME 112 8915
TEST_TIME 113 8887
TEST_TIME 114 8862
TEST_TIME 115 8909
TEST_TIME 116 8897
TEST_TIME 117 8918
TEST_TIME 118 8963
TEST_TIME 119 9016
TEST_TIME 120 9042

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2022 07:48:41 to 05-01-2022 07:48:44.

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 1484130 1484130 0 0.00 0 18151 14 0 0
doc_list_biud 1484120 1484120 0 0.00 0 42364 48 0 0
sp_add_invoice_to_stock 4764 4647 117 2.45 45 202034 26308 0 117
sp_cancel_adding_invoice 5810 5810 0 0.00 7 102287 1236 0 0
sp_cancel_client_order 1633 1633 0 0.00 7 17693 436 0 0
sp_cancel_customer_reserve 565571 565571 0 0.00 1 38045 113 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 4 8764 134 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 5298 170 0 0
sp_cancel_supplier_invoice 6431 6428 3 0.04 2 42368 1101 0 3
sp_cancel_supplier_order 408 408 0 0.00 11 1553804 200752 0 0
sp_cancel_write_off 10656 10656 0 0.00 2 11771 190 0 0
sp_client_order 19201 19201 0 0.00 6 24539 509 0 0
sp_customer_reserve 434953 434953 0 0.00 1 98667 248 0 0
sp_fill_shopping_cart 29439 29439 0 0.00 1 25880 93 0 0
sp_fill_shopping_cart_clo_res 434922 434922 0 0.00 0 21653 22 0 0
sp_get_clo_for_invoice 4609 4609 0 0.00 0 33091 341 0 0
sp_kill_cost_storno 17480 17480 0 0.00 0 8759 84 0 0
sp_kill_qstorno_ret_qs2qd 1148634 1148634 0 0.00 0 42363 48 0 0
sp_kill_qty_storno 604383 604383 0 0.00 0 42364 110 0 0
sp_lock_selected_doc 19183 19183 0 0.00 0 9419 7 0 0
sp_make_cost_storno 25750 25750 0 0.00 0 12684 82 0 0
sp_multiply_rows_for_pdistr 25750 25750 0 0.00 0 9020 42 0 0
sp_multiply_rows_for_qdistr 19201 19201 0 0.00 0 24519 189 0 0
sp_pay_from_customer 6478 6478 0 0.00 5 16352 194 0 0
sp_pay_to_supplier 5393 5393 0 0.00 6 12916 193 0 0
sp_payment_common 11871 11871 0 0.00 2 8125 49 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 0 17653 357 0 0
sp_qd_handle_on_invoice_upd_sts 10418 10418 0 0.00 0 18568 569 0 0
sp_qd_handle_on_reserve_upd_sts 9269 9269 0 0.00 0 18827 70 0 0
sp_reserve_write_off 9270 9270 0 0.00 7 18942 289 0 0
sp_supplier_invoice 5165 5165 0 0.00 10 120702 3927 0 0
sp_supplier_order 5080 5080 0 0.00 9 35468 1499 0 0
srv_aggregate_perf_data 105 105 0 0.00 2815 231918 18636 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 817 139360 37304 0 0
srv_find_qd_qs_mism 1037820 1037820 0 0.00 0 18151 10 0 0
srv_make_invnt_saldo 430 430 0 0.00 46 193146 4792 0 0
srv_make_money_saldo 872 872 0 0.00 1 10322 385 0 0
srv_recalc_idx_stat 6 6 0 0.00 51130 524695 252198 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4648 4648 0 0.00 1 12083 970 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 6029 420 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 19151 37 0 0
t$perf-norm:sp_cancel_customer_reserve 605 605 0 0.00 0 1067 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 3619 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 1496 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 556 33 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 105611 8188 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 2716 19 0 0
t$perf-norm:sp_client_order 19201 19201 0 0.00 0 22597 41 0 0
t$perf-norm:sp_customer_reserve 887 887 0 0.00 0 4618 23 0 0
t$perf-norm:sp_pay_from_customer 6478 6478 0 0.00 0 9509 20 0 0
t$perf-norm:sp_pay_to_supplier 5393 5393 0 0.00 0 4811 19 0 0
t$perf-norm:sp_reserve_write_off 9270 9270 0 0.00 0 9388 23 0 0
t$perf-norm:sp_supplier_invoice 5165 5165 0 0.00 0 3315 33 0 0
t$perf-norm:sp_supplier_order 5080 5080 0 0.00 0 3136 32 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 275 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1223 24 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 1329 22 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 606 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 8 328 93 0 0
v_add_invoice_to_stock 9448 9214 234 2.47 0 9182 24 0 234
v_all_customers 35338 35338 0 0.00 0 149 4 0 0
v_all_suppliers 28650 28650 0 0.00 0 84 4 0 0
v_all_wares 38398 38398 0 0.00 0 138 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 219 12 0 0
v_cancel_client_order 3266 3266 0 0.00 0 6183 13 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 4108 16 0 0
v_cancel_customer_reserve 1210 1210 0 0.00 0 2985 15 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 3245 19 0 0
v_cancel_supplier_invoice 736 730 6 0.81 0 1724 27 0 6
v_cancel_supplier_order 816 816 0 0.00 0 2979 21 0 0
v_cancel_write_off 1760 1760 0 0.00 0 6642 31 0 0
v_max_id_clo_ord 5079 5079 0 0.00 0 2262 5 0 0
v_max_id_clo_res 887 887 0 0.00 0 1437 13 0 0
v_max_id_ord_sup 5158 5158 0 0.00 0 9178 11 0 0
v_max_non_paid_invoice 1304 1304 0 0.00 0 261 8 0 0
v_max_non_paid_realizn 3115 3115 0 0.00 0 4412 10 0 0
v_min_id_clo_ord 5079 5079 0 0.00 0 7438 10 0 0
v_min_id_clo_res 887 887 0 0.00 0 2599 11 0 0
v_min_id_ord_sup 5158 5158 0 0.00 0 7053 16 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 6509 17 0 0
v_min_non_paid_realizn 6478 6478 0 0.00 0 8120 21 0 0
v_our_firm 9786 9786 0 0.00 0 65 4 0 0
v_reserve_write_off 18538 18538 0 0.00 0 11254 13 0 0
x_lock_depdocs_on_canc_invoice 11873 11873 0 0.00 0 44770 140 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 3048 386 0 0
x_make_qstorno_1000_1200 5080 5080 0 0.00 3 29314 1324 0 0
x_make_qstorno_1200_2000 5165 5165 0 0.00 5 120683 3655 0 0
x_make_qstorno_2100_3300 434950 434950 0 0.00 0 98646 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 07:48:45 to 05-01-2022 07:48:49.

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
05:17:55 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:56 32574 8192000000 9739210368 10040410112 818137040 9.080 100 91 182 0 847695104 7846144 21711920
05:20:32 25244 8192000000 9774005488 10082521088 848071392 9.381 100 94 188 0 884639984 14059760 22508832
05:22:04 16791 8192000000 9813414592 10133012480 878485360 9.685 100 83 166 0 925248432 27644704 19118368
05:23:24 9559 8192000000 9863607440 10193346560 918443680 10.081 100 88 169 1 967657840 30611104 18603056
05:24:37 2856 8192000000 9941886656 10285469696 969198464 10.579 100 90 177 0 1049754528 62484384 18071680
05:25:42 3513 8192000000 9980815904 10334945280 1004441072 10.922 100 92 175 0 1093539920 71322016 17776832
05:26:49 947 8192000000 10018404160 10380709888 1029040784 11.159 100 95 185 0 1140417248 92253568 19122896
05:27:52 859 8192000000 10033843376 10404392960 1052030704 11.380 100 95 180 0 1157448336 87539568 17878064
05:28:55 1302 8192000000 10044657856 10418495488 1061825184 11.474 100 96 191 0 1164061280 83733120 18502976
05:29:57 1205 8192000000 10046684096 10422595584 1074762016 11.598 100 92 178 0 1169130800 75755712 18613072
05:30:59 3147 8192000000 10047598256 10430353408 1078854928 11.637 100 71 130 0 1169667888 77774368 13038592
05:32:04 1070 8192000000 10054938416 10440933376 1084071376 11.686 100 80 155 0 1177495456 77559520 15864560
05:33:06 1521 8192000000 10064204800 10451226624 1085652912 11.701 100 76 148 0 1182094976 82273648 14168416
05:34:08 2804 8192000000 10048183120 10439643136 1089659776 11.739 100 71 135 0 1165265792 62367568 13238448
05:35:11 2632 8192000000 10033562608 10420867072 1092629840 11.768 100 78 144 2 1151822432 44825120 14367472
05:36:15 926 8192000000 10063335424 10452156416 1098402448 11.822 100 88 170 1 1182440032 66291552 17746032
05:37:20 1200 8192000000 10048641904 10432458752 1095406368 11.794 100 98 184 2 1166725552 52647280 18671904
05:38:22 1072 8192000000 10076876624 10466627584 1092936800 11.771 100 91 181 1 1190509008 80030864 17541344
05:39:24 4528 8192000000 10060630576 10448404480 1091176976 11.754 100 68 132 0 1176346528 71267216 13902336
05:40:29 971 8192000000 10025432320 10406785024 1075336752 11.603 99 84 156 3 1145460048 55208432 14914864
05:41:31 992 8192000000 10035454560 10423910400 1089031376 11.733 100 92 173 1 1155450288 48666896 17752016
05:42:32 1180 8192000000 10020144768 10396635136 1053862176 11.398 99 77 146 3 1135503696 66848816 14792704
05:43:34 1190 8192000000 10025758752 10400407552 1040094496 11.266 99 91 181 0 1148154048 89057344 19002208
05:44:36 1071 8192000000 9996903072 10369277952 1035804480 11.224 100 90 172 3 1109982032 56120688 18056864
05:45:38 9132 8192000000 9973998896 10354479104 1035779536 11.224 100 66 123 2 1093877504 46630432 11467536
05:46:48 1684 8192000000 9959247600 10335719424 1019402480 11.066 99 66 130 1 1080976608 47997904 13576224
05:47:50 2410 8192000000 9979053680 10361622528 1052843200 11.388 100 68 130 1 1107026080 40770400 13412480
05:49:01 12918 8192000000 10010359616 10389651456 1048975280 11.351 98 74 143 2 1122056880 58673904 14407696
05:50:24 3539 8192000000 10035746896 10418757632 1084462032 11.690 100 69 134 1 1155838576 57538144 13838400
05:51:32 3727 8192000000 10087887120 10468786176 1093351696 11.775 100 72 141 0 1202701200 94629056 14720448
05:52:42 2234 8192000000 10102996416 10496114688 1110384016 11.936 100 72 141 2 1221612448 96027152 15201280
05:53:49 5866 8192000000 10153544704 10551488512 1122862000 12.054 100 83 161 2 1272070912 132203648 17005264
05:54:59 18334 8192000000 10186504544 10581561344 1125045872 12.075 100 66 128 0 1290340512 151258560 14036080
05:56:21 2640 8192000000 10215011120 10609811456 1144264000 12.256 100 83 154 4 1323575136 163530080 15781056
05:57:28 1115 8192000000 10267520848 10676305920 1155290752 12.359 100 97 188 0 1378372208 201853584 21227872
05:58:33 3389 8192000000 10304936608 10704506880 1170601312 12.502 100 78 156 1 1417009456 228610336 17797808
05:59:44 2111 8192000000 10331497616 10734944256 1174351856 12.537 100 91 177 0 1444531840 248961744 21218240
06:00:51 1596 8192000000 10356099424 10770059264 1181792144 12.607 100 83 159 0 1468720960 268390080 18538736
06:01:57 1756 8192000000 10377810832 10815311872 1180428048 12.594 99 85 166 1 1482969760 282283568 20258144
06:03:03 919 8192000000 10394869968 10799566848 1192193632 12.704 100 95 181 3 1504162592 290741024 21227936
06:04:07 2620 8192000000 10425553088 10838216704 1186710208 12.653 100 84 162 1 1521120272 315103952 19306112
06:05:14 1279 8192000000 10473537680 10897235968 1206735088 12.839 100 95 179 0 1579113488 351840800 20537600
06:06:18 1206 8192000000 10492368208 10958446592 1208738176 12.857 100 98 194 2 1597640432 366080976 22821280
06:07:23 1014 8192000000 10522475808 10928996352 1214749344 12.913 100 94 181 3 1628360272 392698592 20912336
06:08:26 1193 8192000000 10537081424 10968248320 1205618848 12.828 100 98 194 2 1639767056 411445376 22702832
06:09:30 1030 8192000000 10575809232 10983157760 1202268064 12.797 100 97 192 5 1677343008 450995424 24079520
06:10:34 1315 8192000000 10647015632 11055919104 1208571648 12.856 100 95 189 1 1753680656 520331872 24777136
06:11:37 805 8192000000 10666581440 11117539328 1205847776 12.831 100 98 194 1 1767755312 537552992 24354544
06:12:41 935 8192000000 10651650976 11137462272 1199242960 12.769 100 96 193 1 1750754000 527663696 23847344
06:13:47 8732 8192000000 10654255008 11098464256 1199346352 12.770 100 63 118 0 1760917792 545738288 15833152
06:14:59 15832 8192000000 10628256464 11066224640 1195865856 12.738 100 52 102 2 1726473104 516978752 13628496
06:16:22 10023 8192000000 10682026192 11145461760 1211277088 12.881 100 53 106 2 1800372272 575155904 13939280
06:17:43 15472 8192000000 10704851952 11164995584 1197181936 12.750 100 45 89 0 1800868272 592077136 11609200
06:19:17 7855 8192000000 10646213744 11139891200 1180790608 12.598 100 50 97 4 1747636176 554019904 12825664
06:20:27 12322 8192000000 10581047648 11118698496 1175299552 12.546 99 41 82 0 1671734592 485394608 11040432
06:21:48 20136 8192000000 10398096224 10931146752 1177675104 12.569 99 35 70 0 1523959360 337494544 8789712
06:23:19 20718 8192000000 10244240816 10822033408 1166652864 12.466 100 17 34 2 1387317616 216008640 4656112
06:25:06 29129 8192000000 10119255472 10596593664 1141724576 12.232 98 40 78 2 1201486432 51335168 8426688
06:26:40 116594 8192000000 10129991472 10579800064 1127178400 12.095 98 21 42 0 1207518944 76335776 4004768
06:29:53 118080 8192000000 10119968592 10571235328 1136751792 12.185 99 17 32 1 1199640640 59120720 3768128
06:33:52 139336 8192000000 10120677584 10580275200 1115080032 11.980 96 19 38 0 1187073392 67930880 4062480
06:37:13 98252 8192000000 10074814304 10544234496 1122001088 12.046 98 25 50 0 1149354752 21317184 6036480
06:39:53 52820 8192000000 10065758144 10517872640 1108209664 11.915 97 29 57 1 1138748368 24648768 5889936
06:41:50 36899 8192000000 10069659360 10509824000 1132649296 12.146 100 35 69 1 1177874240 37729488 7495456
06:43:31 46631 8192000000 10149824992 10592280576 1144797264 12.261 100 42 84 3 1257667120 104019136 8850720
06:45:22 39894 8192000000 10144403104 10623488000 1154492848 12.352 100 36 70 0 1253665632 91408080 7764704
06:47:05 134751 8192000000 10137456400 10613743616 1114218448 11.972 97 12 25 0 1180321856 63724288 2379120
06:50:39 75364 8192000000 10156479568 10640506880 1143549408 12.249 99 20 40 0 1261726448 113843184 4333856
06:52:59 44378 8192000000 10073721952 10524901376 1069375984 11.546 94 19 38 2 1120657248 47242960 4038304
06:54:45 86759 8192000000 10085313776 10522820608 1079069552 11.639 95 24 48 1 1137224752 52648208 5506992
06:57:12 27157 8192000000 10083319856 10514882560 1122336656 12.049 99 54 108 0 1184181584 50558032 11286896
06:58:40 7956 8192000000 10142352944 10564718592 1144603152 12.259 100 64 126 0 1230001120 72874000 12523968
06:59:49 90054 8192000000 10088519840 10582065152 1098481216 11.823 96 15 30 0 1197228880 95141088 3606576
07:02:36 72563 8192000000 10115450320 10570878976 1124522912 12.070 98 25 50 0 1200252448 70566096 5163440
07:05:30 60396 8192000000 10081691264 10511310848 1141648768 12.231 100 72 123 4 1174235344 18801984 13784592
07:07:38 22073 8192000000 10092264688 10536796160 1126561264 12.089 99 39 73 1 1191435792 57466480 7408048
07:09:05 82586 8192000000 10090678064 10547847168 1130931056 12.130 99 15 30 0 1207574480 73549616 3093808
07:11:50 106037 8192000000 10144530304 10603642880 1127298736 12.096 99 17 34 0 1236338960 104880224 4160000
07:14:37 66325 8192000000 10056002704 10499817472 1085954144 11.704 96 15 30 1 1119267328 30175744 3137440
07:17:05 30659 8192000000 10094252032 10538811392 1118666336 12.014 99 31 62 2 1176368000 51914192 5787472
07:18:38 52253 8192000000 10104268816 10541326336 1115544576 11.985 98 28 56 3 1211946368 90089456 6312336
07:20:30 21213 8192000000 10106344464 10530430976 1115924032 11.988 99 59 114 2 1192188144 64117792 12146320
07:21:53 47196 8192000000 10074904064 10527612928 1113706528 11.967 99 21 42 0 1168916016 50936816 4272672
07:23:44 47032 8192000000 10100559776 10546421760 1102140080 11.858 97 17 34 0 1145114016 39260608 3713328
07:25:41 69894 8192000000 10088907136 10615242752 1129584128 12.117 99 27 53 1 1245365056 110258304 5522624
07:27:53 108762 8192000000 10118144608 10582528000 1131974816 12.140 99 45 90 0 1218107856 76766192 9366848
07:31:13 11190 8192000000 10096094864 10544046080 1146332432 12.275 100 41 82 1 1194098448 39654336 8111680
07:32:26 83445 8192000000 10099086944 10550484992 1128912144 12.111 99 21 42 0 1175805152 42864896 4028112
07:34:54 76589 8192000000 10055921600 10532319232 1106433248 11.899 97 18 33 3 1149692800 39501824 3757728
07:37:12 89655 8192000000 10102486032 10532134912 1114049072 11.971 98 15 29 1 1168440976 51126032 3265872
07:39:44 32783 8192000000 10121213872 10565054464 1124582704 12.070 98 24 47 1 1201396480 71295664 5518112
07:41:24 19563 8192000000 10165983888 10598039552 1132772352 12.147 100 38 76 4 1267541376 125807888 8961136
07:42:44 39222 8192000000 10169507952 10610434048 1139568080 12.211 100 25 50 1 1261394576 115951904 5874592
07:44:34 50731 8192000000 10192572032 10647433216 1130669472 12.128 99 37 72 2 1299798080 160203952 8924656
07:46:39 54471 8192000000 10093739856 10551803904 1122019008 12.046 99 19 38 1 1205343424 79193904 4130512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-01-2022 07:48:51 to 05-01-2022 07:49:07.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 07:49:08 to 05-01-2022 07:49:10.

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          207669
MON$OLDEST_ACTIVE               209615
MON$OLDEST_SNAPSHOT             209615
MON$NEXT_TRANSACTION            209615
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                       2080480
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             758
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Jan  5 07:47:54 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Jan  5 07:48:00 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Jan  5 07:48:00 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

05.01.22 07:49:11. 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 07:49:13 No severe PSQL-related problems occured
05.01.22 07:49:14. End of report.