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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-17 04:37:25
DTS_END                         2023-01-17 04:37:25.6720
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/40882
TRN_ID                          216589
ATT_ID                          623
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: 9058.45 21776 1087014
customer order: creation 168.68 502 20242
customer order: refuse 14.19 385 1703
order to supplier: creation 44.85 1456 5382
order to supplier: removal 3.49 154635 419
invoice (draft): creation 43.66 3222 5239
invoice (draft): removal 51.69 978 6203
invoice accept: apply 39.54 25667 4745
invoice accept: cancel 46.98 1084 5637
customer reserve: creation 3729.38 241 447525
customer reserve: removal 4629.40 91 555528
realization accept: apply 82.22 282 9866
realization accept: cancel 79.85 169 9582
payment from customer: creation 57.15 170 6858
payment from customer: removal 5.34 173 641
payment to supplier: creation 46.91 199 5629
payment to supplier: removal 3.25 165 390
service: total inventory turnovers 4.03 3695 484
service: total monetary turnovers 7.03 356 843
service: aggregate perf. data 0.78 16625 93
service: refresh index statistics 0.04 225423 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2023 04:38:30 to 17-01-2023 04:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9451
TEST_TIME 2 6537
TEST_TIME 3 5526
TEST_TIME 4 5819
TEST_TIME 5 6048
TEST_TIME 6 5862
TEST_TIME 7 5700
TEST_TIME 8 6009
TEST_TIME 9 6124
TEST_TIME 10 6161
TEST_TIME 11 6491
TEST_TIME 12 6711
TEST_TIME 13 6985
TEST_TIME 14 7055
TEST_TIME 15 7021
TEST_TIME 16 7336
TEST_TIME 17 7612
TEST_TIME 18 7612
TEST_TIME 19 7828
TEST_TIME 20 7925
TEST_TIME 21 8017
TEST_TIME 22 8473
TEST_TIME 23 8655
TEST_TIME 24 9035
TEST_TIME 25 9131
TEST_TIME 26 9083
TEST_TIME 27 9164
TEST_TIME 28 9414
TEST_TIME 29 9906
TEST_TIME 30 9919
TEST_TIME 31 10386
TEST_TIME 32 10809
TEST_TIME 33 10799
TEST_TIME 34 10564
TEST_TIME 35 10607
TEST_TIME 36 10682
TEST_TIME 37 10434
TEST_TIME 38 10460
TEST_TIME 39 10355
TEST_TIME 40 10216
TEST_TIME 41 10235
TEST_TIME 42 10189
TEST_TIME 43 10140
TEST_TIME 44 10076
TEST_TIME 45 10020
TEST_TIME 46 9950
TEST_TIME 47 9930
TEST_TIME 48 9989
TEST_TIME 49 10013
TEST_TIME 50 9953
TEST_TIME 51 10048
TEST_TIME 52 9868
TEST_TIME 53 9724
TEST_TIME 54 9745
TEST_TIME 55 9704
TEST_TIME 56 9626
TEST_TIME 57 9560
TEST_TIME 58 9462
TEST_TIME 59 9370
TEST_TIME 60 9409
TEST_TIME 61 9354
TEST_TIME 62 9267
TEST_TIME 63 9298
TEST_TIME 64 9341
TEST_TIME 65 9344
TEST_TIME 66 9331
TEST_TIME 67 9348
TEST_TIME 68 9405
TEST_TIME 69 9381
TEST_TIME 70 9362
TEST_TIME 71 9316
TEST_TIME 72 9300
TEST_TIME 73 9469
TEST_TIME 74 9421
TEST_TIME 75 9535
TEST_TIME 76 9618
TEST_TIME 77 9572
TEST_TIME 78 9541
TEST_TIME 79 9570
TEST_TIME 80 9514
TEST_TIME 81 9439
TEST_TIME 82 9367
TEST_TIME 83 9352
TEST_TIME 84 9311
TEST_TIME 85 9271
TEST_TIME 86 9283
TEST_TIME 87 9222
TEST_TIME 88 9262
TEST_TIME 89 9186
TEST_TIME 90 9194
TEST_TIME 91 9172
TEST_TIME 92 9163
TEST_TIME 93 9119
TEST_TIME 94 9096
TEST_TIME 95 9117
TEST_TIME 96 9070
TEST_TIME 97 9078
TEST_TIME 98 9102
TEST_TIME 99 9161
TEST_TIME 100 9207
TEST_TIME 101 9231
TEST_TIME 102 9281
TEST_TIME 103 9325
TEST_TIME 104 9333
TEST_TIME 105 9289
TEST_TIME 106 9233
TEST_TIME 107 9216
TEST_TIME 108 9176
TEST_TIME 109 9141
TEST_TIME 110 9090
TEST_TIME 111 9065
TEST_TIME 112 9060
TEST_TIME 113 9028
TEST_TIME 114 9028
TEST_TIME 115 9021
TEST_TIME 116 9040
TEST_TIME 117 9094
TEST_TIME 118 9088
TEST_TIME 119 9098
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2023 04:38:34 to 17-01-2023 04:38:37.

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 1500261 1500261 0 0.00 0 17919 12 0 0
doc_list_biud 1500248 1500248 0 0.00 0 65317 40 0 0
sp_add_invoice_to_stock 4900 4745 155 3.16 71 176314 25667 0 155
sp_cancel_adding_invoice 5637 5637 0 0.00 5 87471 1084 0 0
sp_cancel_client_order 1703 1703 0 0.00 6 19934 385 0 0
sp_cancel_customer_reserve 555528 555528 0 0.00 1 23728 91 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 4 11820 173 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 19772 165 0 0
sp_cancel_supplier_invoice 6216 6203 13 0.20 2 65397 978 0 13
sp_cancel_supplier_order 419 419 0 0.00 9 1270888 154636 0 0
sp_cancel_write_off 9582 9582 0 0.00 2 12068 170 0 0
sp_client_order 20242 20242 0 0.00 5 27361 502 0 0
sp_customer_reserve 447525 447525 0 0.00 1 111656 241 0 0
sp_fill_shopping_cart 30847 30847 0 0.00 1 21697 83 0 0
sp_fill_shopping_cart_clo_res 447488 447488 0 0.00 0 20410 21 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 31239 352 0 0
sp_kill_cost_storno 16250 16250 0 0.00 0 12050 72 0 0
sp_kill_qstorno_ret_qs2qd 1127260 1127260 0 0.00 0 65307 41 0 0
sp_kill_qty_storno 593637 593637 0 0.00 0 65310 94 0 0
sp_lock_selected_doc 20115 20115 0 0.00 0 9717 7 0 0
sp_make_cost_storno 27054 27054 0 0.00 0 17903 81 0 0
sp_multiply_rows_for_pdistr 27053 27053 0 0.00 0 8766 38 0 0
sp_multiply_rows_for_qdistr 20242 20242 0 0.00 0 25110 196 0 0
sp_pay_from_customer 6858 6858 0 0.00 5 18637 170 0 0
sp_pay_to_supplier 5629 5629 0 0.00 6 23613 199 0 0
sp_payment_common 12487 12487 0 0.00 2 18586 45 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 8263 305 0 0
sp_qd_handle_on_invoice_upd_sts 10337 10337 0 0.00 0 22921 489 0 0
sp_qd_handle_on_reserve_upd_sts 9865 9865 0 0.00 0 13528 65 0 0
sp_reserve_write_off 9866 9866 0 0.00 9 23433 283 0 0
sp_supplier_invoice 5239 5239 0 0.00 9 94131 3223 0 0
sp_supplier_order 5382 5382 0 0.00 7 66815 1457 0 0
srv_aggregate_perf_data 93 93 0 0.00 2666 190661 16625 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 809 123384 37400 0 0
srv_find_qd_qs_mism 1040204 1040204 0 0.00 0 15579 9 0 0
srv_make_invnt_saldo 484 484 0 0.00 70 78985 3695 0 0
srv_make_money_saldo 843 843 0 0.00 1 14300 357 0 0
srv_recalc_idx_stat 5 5 0 0.00 66388 312148 225423 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4750 4750 0 0.00 1 14848 869 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 7715 413 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 7816 39 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 7983 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 2234 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 6745 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 884 26 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 84424 6151 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 462 16 0 0
t$perf-norm:sp_client_order 20242 20242 0 0.00 0 10642 38 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 8693 27 0 0
t$perf-norm:sp_pay_from_customer 6860 6860 0 0.00 0 9227 20 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 9814 20 0 0
t$perf-norm:sp_reserve_write_off 9866 9866 0 0.00 0 7848 24 0 0
t$perf-norm:sp_supplier_invoice 5239 5239 0 0.00 0 8374 35 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 17782 35 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 255 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 683 14 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 583 13 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 2211 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 204 73 0 0
v_add_invoice_to_stock 9702 9392 310 3.19 0 23358 38 0 310
v_all_customers 36738 36738 0 0.00 0 70 3 0 0
v_all_suppliers 29706 29706 0 0.00 0 181 4 0 0
v_all_wares 40482 40482 0 0.00 0 101 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 6740 25 0 0
v_cancel_client_order 3406 3406 0 0.00 0 19696 18 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 11648 26 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1361 10 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 19729 37 0 0
v_cancel_supplier_invoice 796 770 26 3.26 0 5081 37 0 26
v_cancel_supplier_order 838 838 0 0.00 0 978 10 0 0
v_cancel_write_off 1900 1900 0 0.00 0 2209 27 0 0
v_max_id_clo_ord 5378 5378 0 0.00 0 3119 6 0 0
v_max_id_clo_res 997 997 0 0.00 0 3471 17 0 0
v_max_id_ord_sup 5227 5227 0 0.00 0 2583 6 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 2912 9 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 8826 9 0 0
v_min_id_clo_ord 5378 5378 0 0.00 0 6922 10 0 0
v_min_id_clo_res 997 997 0 0.00 0 2657 13 0 0
v_min_id_ord_sup 5227 5227 0 0.00 0 6435 12 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 5416 17 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 18559 18 0 0
v_our_firm 10108 10108 0 0.00 0 65 4 0 0
v_reserve_write_off 19728 19728 0 0.00 0 23035 15 0 0
x_lock_depdocs_on_canc_invoice 11455 11455 0 0.00 0 17773 127 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 2771 312 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 2 66751 1307 0 0
x_make_qstorno_1200_2000 5239 5239 0 0.00 4 85818 2965 0 0
x_make_qstorno_2100_3300 447524 447524 0 0.00 0 111654 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-01-2023 04:38:38 to 17-01-2023 04:38:42.

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
02:07:29 30 8192000000 8809390096 8824680448 8485184 0.103 5 1 2 0 8589936 68976 35776
02:08:30 17599 8192000000 9717199648 10016366592 797776624 8.874 100 92 184 1 828456512 8016368 22663520
02:09:55 17061 8192000000 9760922608 10067484672 837049856 9.270 100 87 174 0 869680896 11935296 20695744
02:11:15 14922 8192000000 9795047440 10113445888 868573728 9.586 100 89 178 0 906552464 17774240 20204496
02:12:38 10441 8192000000 9858721120 10189152256 912241552 10.019 100 87 172 1 964864608 33292880 19330176
02:13:55 4005 8192000000 9932342096 10275315712 953183392 10.422 100 86 168 1 1045932576 75014800 17734384
02:15:04 1968 8192000000 9944900448 10299113472 994107856 10.821 100 84 166 1 1069339536 57864592 17367088
02:16:08 1450 8192000000 9999149392 10359230464 1021398768 11.086 100 93 179 2 1111997504 72781824 17816912
02:17:12 1302 8192000000 10014024128 10389086208 1046662752 11.329 100 98 187 1 1135510448 69651584 19196112
02:18:15 1125 8192000000 10027257952 10401226752 1061663056 11.472 100 93 179 4 1152291440 72780064 17848320
02:19:19 3717 8192000000 10012366848 10385416192 1066547328 11.519 100 85 150 1 1132313712 51842976 13923408
02:20:25 1313 8192000000 10068376432 10448830464 1078578224 11.634 100 99 192 3 1186653520 88682912 19392384
02:21:28 1104 8192000000 10051153840 10435153920 1081495360 11.662 100 100 185 2 1171085424 70419728 19170336
02:22:31 1130 8192000000 10064190000 10450980864 1088257424 11.726 100 93 177 0 1185767536 79479648 18030464
02:23:32 1150 8192000000 10063825904 10449809408 1088552224 11.729 100 98 190 2 1185366800 77866048 18948528
02:24:34 1024 8192000000 10054377232 10440400896 1096291568 11.802 100 96 183 2 1179198544 64524640 18382336
02:25:37 1114 8192000000 10049070064 10437791744 1096369424 11.803 100 95 185 4 1173470896 57420656 19680816
02:26:39 4591 8192000000 10049297408 10445328384 1100460352 11.842 100 65 122 1 1172545136 59946752 12138032
02:27:45 3941 8192000000 10058170336 10445651968 1100839376 11.846 100 86 160 2 1178532288 62046240 15646672
02:28:49 6374 8192000000 10044476912 10436841472 1084297424 11.688 99 67 124 2 1160064848 61480928 14286496
02:29:57 1148 8192000000 10052019840 10436313088 1084556000 11.691 100 98 187 1 1177407136 74004528 18846608
02:30:59 993 8192000000 10039200032 10424426496 1090712384 11.749 100 96 177 0 1166937440 57724064 18500992
02:32:01 988 8192000000 10048685600 10434646016 1078239952 11.631 100 91 172 0 1173234608 75910016 19084640
02:33:02 882 8192000000 9990369216 10367328256 1034939264 11.216 100 83 156 1 1111495984 59591488 16965232
02:34:05 4459 8192000000 9977621536 10365460480 1039468608 11.260 100 57 113 1 1104452112 53682784 11300720
02:35:10 10453 8192000000 9990361248 10370248704 1046948656 11.331 100 65 122 1 1115611552 56212336 12450560
02:36:24 1404 8192000000 9999929584 10380013568 1054709136 11.406 100 94 180 1 1121313856 48378576 18226144
02:37:26 1314 8192000000 10000893056 10380865536 1065545680 11.510 100 91 173 2 1123778192 40196704 18035808
02:38:30 1159 8192000000 10023855264 10402017280 1070677088 11.559 99 89 157 2 1146292528 60902064 14713376
02:39:38 17409 8192000000 10040427472 10428817408 1071899760 11.570 99 68 132 1 1156997168 71882704 13214704
02:41:05 5177 8192000000 10089438704 10478039040 1095776448 11.798 100 77 146 3 1203408560 92451744 15180368
02:42:15 1930 8192000000 10110550976 10493784064 1099264544 11.831 100 85 155 1 1225778368 112029520 14484304
02:43:19 17100 8192000000 10148483904 10548944896 1118805248 12.016 100 53 101 1 1267208480 137164752 11238480
02:44:50 1619 8192000000 10182057232 10582405120 1136620864 12.184 100 89 171 5 1289793968 134339600 18833504
02:45:55 2029 8192000000 10213421248 10611486720 1145660944 12.269 100 80 152 2 1327645456 164594736 17389776
02:47:01 1218 8192000000 10256454912 10653478912 1156183952 12.368 100 93 183 0 1369339904 192471632 20684320
02:48:07 2128 8192000000 10275246352 10684600320 1157980016 12.384 100 84 162 1 1391136352 213690336 19466000
02:49:11 809 8192000000 10308930288 10716602368 1165514096 12.455 100 98 193 0 1423477568 236042176 21921296
02:50:15 984 8192000000 10328046656 10747367424 1179487136 12.585 100 95 182 3 1429706880 228920176 21299568
02:51:20 841 8192000000 10332682032 10736451584 1173802880 12.532 100 99 186 1 1443366768 248944640 20619248
02:52:23 906 8192000000 10376678336 10785316864 1183472816 12.623 100 98 191 0 1488591984 282847232 22271936
02:53:27 799 8192000000 10376738880 10812878848 1182461200 12.613 100 99 192 3 1482166048 277995824 21709024
02:54:31 1371 8192000000 10392896240 10811416576 1183307408 12.621 100 92 172 1 1498695840 296006400 19382032
02:55:36 1234 8192000000 10440701312 10874384384 1194752752 12.728 100 95 188 2 1545311264 328645504 21913008
02:56:41 1071 8192000000 10437393840 10861850624 1197805056 12.756 100 96 187 4 1541370080 322207792 21357232
02:57:44 3360 8192000000 10451552432 10895044608 1187486784 12.660 100 83 154 1 1562505472 355211120 19807568
02:58:51 2196 8192000000 10490255616 10926338048 1184005904 12.628 99 77 149 0 1596111344 393049168 19056272
03:00:00 13550 8192000000 10422730032 10897108992 1173052432 12.525 99 42 83 0 1522506432 338425280 11028720
03:01:29 12275 8192000000 10366547568 10844889088 1169850496 12.495 100 35 68 0 1467687456 288878848 8958112
03:02:55 27628 8192000000 10413012544 10849394688 1155686512 12.363 99 39 78 1 1491117776 326208736 9222528
03:04:32 18282 8192000000 10395223904 10864594944 1153618512 12.343 99 37 71 1 1506377440 343556960 9201968
03:05:57 17480 8192000000 10373554160 10868297728 1157957680 12.384 100 41 82 0 1500579824 332157712 10464432
03:07:17 34751 8192000000 10343369904 10796072960 1155901904 12.365 100 26 49 1 1424237728 261967952 6367872
03:08:53 26966 8192000000 10153802160 10647924736 1132897376 12.149 98 24 48 0 1307281824 168980112 5404336
03:10:20 56796 8192000000 10120867584 10544754688 1131242112 12.133 99 67 108 2 1213122096 71235488 10644496
03:12:18 16286 8192000000 10161730976 10637860864 1158230032 12.387 100 37 72 0 1271434928 105994256 7210640
03:13:38 48232 8192000000 10087035712 10560745472 1110446592 11.937 97 11 21 0 1179585264 66615632 2523040
03:15:34 49509 8192000000 10093833536 10531913728 1087713984 11.721 96 41 78 1 1151687296 55806128 8167184
03:17:32 64069 8192000000 10088273264 10532974592 1118190224 12.010 100 29 48 2 1172893024 48178272 6524528
03:19:55 95147 8192000000 10038511392 10496380928 1081642896 11.663 97 11 22 0 1130904048 47329392 1931760
03:23:02 13830 8192000000 10071957920 10490441728 1116619440 11.995 100 62 102 0 1157285744 29803904 10862400
03:24:17 66302 8192000000 10100881920 10599550976 1091462192 11.757 97 24 48 0 1154217472 57980512 4774768
03:26:38 33010 8192000000 10041615040 10457690112 1100495552 11.842 98 50 95 2 1137523280 26814736 10212992
03:28:11 123384 8192000000 10081752384 10541756416 1077770736 11.626 96 9 18 0 1130273056 50737792 1764528
03:31:35 69082 8192000000 10054350656 10484801536 1109255936 11.925 99 52 87 1 1139540992 22245008 8040048
03:33:47 27924 8192000000 10047340960 10467160064 1078827040 11.636 96 44 77 3 1121067904 33790352 8450512
03:35:20 42899 8192000000 10112432896 10543996928 1114803952 11.978 99 23 45 0 1202804016 83031296 4968768
03:37:03 31306 8192000000 10163656016 10596536320 1118431808 12.012 99 39 78 2 1236128320 108620384 9076128
03:38:42 74895 8192000000 10142750400 10599256064 1134399872 12.163 100 17 31 0 1253635440 115192000 4043568
03:40:57 54061 8192000000 10085372512 10611445760 1120658864 12.033 100 8 16 0 1212759104 90424832 1675408
03:42:53 56840 8192000000 10096041552 10554368000 1131076224 12.132 100 18 36 0 1184948784 49832880 4039680
03:44:50 63179 8192000000 10053714096 10499198976 1121166336 12.038 98 17 33 0 1185318288 60628160 3523792
03:46:55 40345 8192000000 10095967728 10526691328 1124532544 12.070 99 23 46 0 1181681264 52392656 4756064
03:48:56 26920 8192000000 10185670896 10607734784 1122981888 12.055 98 58 113 1 1255807584 120515216 12310480
03:50:30 88364 8192000000 10124876640 10609438720 1135308256 12.171 99 19 36 0 1218607600 79674304 3625040
03:52:58 79011 8192000000 10068842496 10496045056 1080165440 11.649 95 12 24 0 1123346288 40876608 2304240
03:55:21 48101 8192000000 10124403824 10559361024 1130257536 12.124 100 32 64 0 1210801104 74196368 6347200
03:57:18 67054 8192000000 10058556336 10473091072 1102536528 11.862 97 70 136 1 1147931104 30388192 15006384
03:59:27 51920 8192000000 10087178240 10542833664 1125773936 12.082 100 39 78 0 1190469328 56782112 7913280
04:01:21 77647 8192000000 10114472496 10557382656 1094702448 11.787 96 20 40 0 1183656784 85065808 3888528
04:03:45 89592 8192000000 10094148032 10539663360 1075227632 11.602 96 11 22 0 1164022496 86325712 2469152
04:06:18 34248 8192000000 10066020480 10526720000 1127684080 12.100 100 12 22 0 1178834896 48018688 3132128
04:07:55 85864 8192000000 10076302512 10527068160 1128016624 12.103 99 15 30 0 1175494944 44266592 3211728
04:10:22 14959 8192000000 10078619904 10515550208 1117741792 12.006 99 38 74 0 1178061488 51132960 9186736
04:11:42 29328 8192000000 10135097792 10572988416 1094862176 11.789 96 37 70 0 1193513296 91241648 7409472
04:13:15 30595 8192000000 10168292256 10602143744 1126464448 12.088 98 29 58 0 1237509824 104976656 6068720
04:14:54 64642 8192000000 10160178672 10597830656 1104135008 11.877 97 23 46 0 1218598416 108924512 5538896
04:17:01 37537 8192000000 10122045824 10603991040 1124006784 12.065 100 26 52 0 1253817824 124306112 5504928
04:19:01 44802 8192000000 10103339792 10577485824 1111164432 11.943 99 32 64 2 1205349104 87649744 6534928
04:20:49 63783 8192000000 10054040784 10501947392 1090794944 11.750 97 26 52 0 1161754096 65947456 5011696
04:22:54 74958 8192000000 10060254976 10500288512 1086195840 11.706 98 54 108 0 1155122464 57961152 10965472
04:25:17 9253 8192000000 10050645952 10484412416 1117827968 12.006 100 51 98 0 1161783152 34133872 9821312
04:26:31 88913 8192000000 10111012864 10556641280 1110120464 11.934 98 13 25 0 1179460016 66507872 2831680
04:29:21 53850 8192000000 10135489376 10606211072 1116159616 11.991 98 24 48 1 1219055760 96790464 6105680
04:31:16 49094 8192000000 10118019024 10584027136 1124662816 12.071 100 16 28 1 1223605104 95727744 3214544
04:33:06 96395 8192000000 10064453968 10513821696 1135227504 12.171 100 17 34 1 1229758272 90443248 4087520
04:35:45 68211 8192000000 10104422816 10561527808 1134074720 12.160 99 24 48 0 1212376480 73136576 5165184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-01-2023 04:38:44 to 17-01-2023 04:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-01-2023 04:39:02 to 17-01-2023 04:39:04.

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          214424
MON$OLDEST_ACTIVE               217023
MON$OLDEST_SNAPSHOT             217023
MON$NEXT_TRANSACTION            217023
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                       2063072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:759: 
:760: mscs-test-161.budzdorov.ru    Tue Jan 17 04:37:35 2023
:761:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:762: 
:763: 
:764: mscs-test-161.budzdorov.ru    Tue Jan 17 04:37:38 2023
:765:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:766: 
--- end of diff output ---

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

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