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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-01 07:46:29
DTS_END                         2022-05-01 07:46:29.9030
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/38472
TRN_ID                          223552
ATT_ID                          595
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: 9130.47 28915 1095656
customer order: creation 170.17 474 20420
customer order: refuse 14.96 405 1795
order to supplier: creation 46.66 1429 5599
order to supplier: removal 3.41 179445 409
invoice (draft): creation 45.82 3250 5498
invoice (draft): removal 50.60 1039 6072
invoice accept: apply 42.05 26815 5046
invoice accept: cancel 45.45 1345 5454
customer reserve: creation 3929.53 251 471543
customer reserve: removal 4487.37 108 538484
realization accept: apply 84.10 291 10092
realization accept: cancel 79.08 171 9489
payment from customer: creation 60.14 177 7217
payment from customer: removal 6.02 136 722
payment to supplier: creation 48.84 204 5861
payment to supplier: removal 3.38 144 405
service: total inventory turnovers 3.76 4497 451
service: total monetary turnovers 8.22 354 986
service: aggregate perf. data 0.91 19141 109
service: refresh index statistics 0.03 338617 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6738
TEST_TIME 2 5188
TEST_TIME 3 6229
TEST_TIME 4 5896
TEST_TIME 5 5769
TEST_TIME 6 6003
TEST_TIME 7 6321
TEST_TIME 8 6514
TEST_TIME 9 6954
TEST_TIME 10 7138
TEST_TIME 11 7440
TEST_TIME 12 7392
TEST_TIME 13 7582
TEST_TIME 14 7717
TEST_TIME 15 7876
TEST_TIME 16 7903
TEST_TIME 17 8093
TEST_TIME 18 8130
TEST_TIME 19 8232
TEST_TIME 20 8382
TEST_TIME 21 8891
TEST_TIME 22 8883
TEST_TIME 23 9049
TEST_TIME 24 9058
TEST_TIME 25 9038
TEST_TIME 26 9021
TEST_TIME 27 8982
TEST_TIME 28 8979
TEST_TIME 29 9116
TEST_TIME 30 9260
TEST_TIME 31 9461
TEST_TIME 32 9724
TEST_TIME 33 9968
TEST_TIME 34 10262
TEST_TIME 35 10324
TEST_TIME 36 10998
TEST_TIME 37 10850
TEST_TIME 38 11307
TEST_TIME 39 11292
TEST_TIME 40 11388
TEST_TIME 41 11195
TEST_TIME 42 11066
TEST_TIME 43 11265
TEST_TIME 44 11130
TEST_TIME 45 10902
TEST_TIME 46 10766
TEST_TIME 47 10682
TEST_TIME 48 10616
TEST_TIME 49 10473
TEST_TIME 50 10317
TEST_TIME 51 10324
TEST_TIME 52 10255
TEST_TIME 53 10154
TEST_TIME 54 10160
TEST_TIME 55 10138
TEST_TIME 56 10092
TEST_TIME 57 10095
TEST_TIME 58 10193
TEST_TIME 59 10150
TEST_TIME 60 10046
TEST_TIME 61 10006
TEST_TIME 62 9910
TEST_TIME 63 9875
TEST_TIME 64 9869
TEST_TIME 65 9814
TEST_TIME 66 9739
TEST_TIME 67 9729
TEST_TIME 68 9797
TEST_TIME 69 9840
TEST_TIME 70 9932
TEST_TIME 71 9955
TEST_TIME 72 9908
TEST_TIME 73 9867
TEST_TIME 74 10001
TEST_TIME 75 10048
TEST_TIME 76 10027
TEST_TIME 77 9986
TEST_TIME 78 9889
TEST_TIME 79 9863
TEST_TIME 80 9807
TEST_TIME 81 9771
TEST_TIME 82 9737
TEST_TIME 83 9669
TEST_TIME 84 9613
TEST_TIME 85 9528
TEST_TIME 86 9506
TEST_TIME 87 9569
TEST_TIME 88 9555
TEST_TIME 89 9551
TEST_TIME 90 9533
TEST_TIME 91 9532
TEST_TIME 92 9509
TEST_TIME 93 9522
TEST_TIME 94 9550
TEST_TIME 95 9536
TEST_TIME 96 9561
TEST_TIME 97 9521
TEST_TIME 98 9600
TEST_TIME 99 9613
TEST_TIME 100 9593
TEST_TIME 101 9528
TEST_TIME 102 9454
TEST_TIME 103 9417
TEST_TIME 104 9414
TEST_TIME 105 9387
TEST_TIME 106 9351
TEST_TIME 107 9299
TEST_TIME 108 9240
TEST_TIME 109 9196
TEST_TIME 110 9188
TEST_TIME 111 9166
TEST_TIME 112 9183
TEST_TIME 113 9171
TEST_TIME 114 9148
TEST_TIME 115 9217
TEST_TIME 116 9187
TEST_TIME 117 9172
TEST_TIME 118 9199
TEST_TIME 119 9193
TEST_TIME 120 9153

Performance per minute, chart


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

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 1529490 1529490 0 0.00 0 21170 14 0 0
doc_list_biud 1529487 1529487 0 0.00 0 27006 44 0 0
sp_add_invoice_to_stock 5147 5046 101 1.96 53 210075 26815 0 101
sp_cancel_adding_invoice 5454 5454 0 0.00 7 89330 1346 0 0
sp_cancel_client_order 1795 1795 0 0.00 6 9869 405 0 0
sp_cancel_customer_reserve 538484 538484 0 0.00 1 27010 108 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 4 7665 137 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 5377 144 0 0
sp_cancel_supplier_invoice 6079 6072 7 0.11 3 25512 1040 0 7
sp_cancel_supplier_order 409 409 0 0.00 19 1732058 179445 0 0
sp_cancel_write_off 9489 9489 0 0.00 2 5515 171 0 0
sp_client_order 20420 20420 0 0.00 6 17668 474 0 0
sp_customer_reserve 471543 471543 0 0.00 1 92553 251 0 0
sp_fill_shopping_cart 31512 31512 0 0.00 1 17807 92 0 0
sp_fill_shopping_cart_clo_res 471504 471504 0 0.00 0 14383 18 0 0
sp_get_clo_for_invoice 5007 5007 0 0.00 0 18309 252 0 0
sp_kill_cost_storno 16070 16070 0 0.00 0 10125 78 0 0
sp_kill_qstorno_ret_qs2qd 1092938 1092938 0 0.00 0 26973 46 0 0
sp_kill_qty_storno 576802 576802 0 0.00 0 27003 106 0 0
sp_lock_selected_doc 20956 20956 0 0.00 0 8536 8 0 0
sp_make_cost_storno 28177 28177 0 0.00 0 21136 84 0 0
sp_multiply_rows_for_pdistr 28177 28177 0 0.00 0 19801 43 0 0
sp_multiply_rows_for_qdistr 20420 20420 0 0.00 0 17644 196 0 0
sp_pay_from_customer 7217 7217 0 0.00 5 11586 178 0 0
sp_pay_to_supplier 5861 5861 0 0.00 7 20084 205 0 0
sp_payment_common 13078 13078 0 0.00 2 8125 50 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 1 9844 326 0 0
sp_qd_handle_on_invoice_upd_sts 10461 10461 0 0.00 0 14132 569 0 0
sp_qd_handle_on_reserve_upd_sts 10092 10092 0 0.00 0 19164 56 0 0
sp_reserve_write_off 10092 10092 0 0.00 9 19278 291 0 0
sp_supplier_invoice 5498 5498 0 0.00 9 76273 3250 0 0
sp_supplier_order 5599 5599 0 0.00 7 37486 1430 0 0
srv_aggregate_perf_data 109 109 0 0.00 2245 185063 19142 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 844 109749 34166 0 0
srv_find_qd_qs_mism 1046058 1046058 0 0.00 0 8478 10 0 0
srv_make_invnt_saldo 451 451 0 0.00 71 94613 4497 0 0
srv_make_money_saldo 986 986 0 0.00 1 9669 355 0 0
srv_recalc_idx_stat 4 4 0 0.00 269486 381541 338618 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5048 5048 0 0.00 1 13706 1179 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 9484 578 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 2698 30 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 8353 55 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 5385 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 584 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1383 35 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 1 113648 8312 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 2182 25 0 0
t$perf-norm:sp_client_order 20420 20420 0 0.00 0 9227 41 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 3076 22 0 0
t$perf-norm:sp_pay_from_customer 7217 7217 0 0.00 0 5460 21 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 7966 24 0 0
t$perf-norm:sp_reserve_write_off 10092 10092 0 0.00 0 6304 25 0 0
t$perf-norm:sp_supplier_invoice 5498 5498 0 0.00 0 5420 34 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 2992 36 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 210 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 161 9 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 3215 25 0 0
t$perf-norm:srv_make_money_saldo 986 986 0 0.00 0 2622 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 207 74 0 0
v_add_invoice_to_stock 10214 10012 202 1.97 0 5832 28 0 202
v_all_customers 37610 37610 0 0.00 0 231 4 0 0
v_all_suppliers 30822 30822 0 0.00 0 78 5 0 0
v_all_wares 40838 40838 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 144 14 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1357 10 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 3881 15 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 1 1265 12 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 5343 19 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 420 23 0 14
v_cancel_supplier_order 818 818 0 0.00 0 854 13 0 0
v_cancel_write_off 2038 2038 0 0.00 0 3883 26 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 253 5 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 5722 21 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 3022 8 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 248 9 0 0
v_max_non_paid_realizn 3846 3846 0 0.00 0 525 8 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 3967 9 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 7567 27 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 5760 9 0 0
v_min_non_paid_invoice 5861 5861 0 0.00 0 7990 17 0 0
v_min_non_paid_realizn 7217 7217 0 0.00 0 4172 18 0 0
v_our_firm 9970 9970 0 0.00 0 60 5 0 0
v_reserve_write_off 20184 20184 0 0.00 0 6899 13 0 0
x_lock_depdocs_on_canc_invoice 11117 11117 0 0.00 0 15506 122 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3280 342 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 3 37266 1234 0 0
x_make_qstorno_1200_2000 5498 5498 0 0.00 4 75616 3015 0 0
x_make_qstorno_2100_3300 471543 471543 0 0.00 0 92544 218 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-05-2022 07:48:10 to 01-05-2022 07:48:15.

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:16:33 3 8192000000 8800571168 8812486656 6233184 0.076 2 2 3 0 6374000 79456 61360
05:17:34 31829 8192000000 9738685744 10040508416 810752624 9.005 100 85 170 0 838684256 7441376 20490256
05:19:12 15711 8192000000 9780389888 10089951232 848017056 9.380 100 88 177 1 882120928 13306496 20797376
05:20:41 12698 8192000000 9812486672 10136371200 881145040 9.711 100 82 164 3 923297632 23745424 18407168
05:21:59 6557 8192000000 9876723072 10208714752 921943904 10.115 100 87 173 0 984704880 44184448 18576528
05:23:11 5222 8192000000 9945316912 10289733632 967273504 10.560 100 93 183 1 1057354544 71236368 18844672
05:24:20 2379 8192000000 9958415152 10311041024 993620304 10.817 100 86 166 2 1078703552 68149104 16934144
05:25:24 2143 8192000000 9979650016 10343948288 1017962320 11.052 100 81 158 0 1098538144 64513824 16062000
05:26:28 946 8192000000 10029038496 10403799040 1044489392 11.308 100 94 177 2 1153542112 90252064 18800656
05:27:30 1014 8192000000 10033259616 10405466112 1058053568 11.438 100 97 182 1 1155474464 78373472 19047424
05:28:33 1096 8192000000 10042965856 10418622464 1067879040 11.532 100 96 184 0 1161272000 75581088 17811872
05:29:35 8387 8192000000 10038286784 10426572800 1070620176 11.558 100 75 149 2 1157992432 71441264 15930992
05:30:46 937 8192000000 10047124240 10427121664 1078300544 11.631 100 93 181 0 1166404480 68977520 19126416
05:31:49 952 8192000000 10061276512 10446761984 1081816624 11.665 100 97 190 1 1177743344 75977872 19948848
05:32:51 1010 8192000000 10049069888 10441125888 1082941808 11.675 100 69 135 1 1164577616 66227536 15408272
05:33:52 1187 8192000000 10086416832 10476949504 1091753920 11.759 100 97 188 0 1207427504 94907376 20766208
05:34:54 1178 8192000000 10059029200 10448584704 1092404352 11.766 100 91 174 2 1178067888 67135776 18527760
05:35:56 1593 8192000000 10059492160 10450731008 1084027520 11.686 99 82 161 0 1185422944 84784112 16611312
05:36:59 1843 8192000000 10053930304 10449408000 1091896112 11.761 100 75 137 1 1174995664 69490144 13609408
05:38:02 4342 8192000000 10048013056 10436587520 1082517408 11.671 100 75 137 0 1170388128 72615536 15255184
05:39:09 3638 8192000000 10048698016 10437713920 1084871904 11.694 100 78 147 2 1167075888 67750384 14453600
05:40:13 978 8192000000 10019466992 10403876864 1062689072 11.482 99 85 168 1 1142719024 61423824 18606128
05:41:15 1072 8192000000 9996528912 10371985408 1041771248 11.282 100 90 173 1 1115614896 56694288 17149360
05:42:17 5306 8192000000 10006304976 10393702400 1025249728 11.123 97 59 114 2 1124724848 88502672 10972448
05:43:24 1009 8192000000 9966881776 10339753984 1021880032 11.090 100 88 171 0 1091458720 52010144 17568544
05:44:25 1320 8192000000 9997726160 10370805760 1033885040 11.206 100 93 177 3 1113193040 61788048 17519952
05:45:27 2650 8192000000 9945902160 10315976704 1020850384 11.080 98 65 119 1 1057908768 24578512 12479872
05:46:36 15859 8192000000 9976101440 10362728448 1039525264 11.260 100 58 113 1 1106415696 54798464 12091968
05:48:15 7955 8192000000 10040417328 10422145024 1055736768 11.416 100 94 187 2 1157910320 81974432 20199120
05:49:26 12003 8192000000 10056427312 10446073856 1078149408 11.630 100 75 141 2 1184929600 91924176 14856016
05:50:43 2989 8192000000 10097261296 10480209920 1092867808 11.770 100 82 144 3 1214549152 106941712 14739632
05:51:56 1503 8192000000 10134543024 10530385920 1100259184 11.840 100 90 171 2 1254551104 135064832 19227088
05:53:02 2847 8192000000 10152286144 10542919680 1113466944 11.965 100 86 163 2 1261411632 131605040 16339648
05:54:08 3506 8192000000 10204030416 10600783872 1125839456 12.082 100 80 158 1 1322586128 179517792 17228880
05:55:17 1288 8192000000 10212169312 10611314688 1143800672 12.251 100 92 182 0 1328924000 164141312 20982016
05:56:23 1556 8192000000 10249576688 10643308544 1149958176 12.309 100 95 180 1 1365088400 195591152 19539072
05:57:26 1518 8192000000 10268019040 10683670528 1162240528 12.424 100 94 176 1 1381507856 200561968 18705360
05:58:31 2268 8192000000 10293791184 10705514496 1169169776 12.489 100 92 183 0 1404336528 215303632 19863120
05:59:38 1946 8192000000 10298231392 10703962112 1175833216 12.551 100 95 182 1 1414069584 217860320 20376048
06:00:45 830 8192000000 10308357024 10716815360 1176246816 12.555 100 93 183 2 1417388704 221009648 20132240
06:01:51 1238 8192000000 10340429760 10755420160 1184964592 12.636 100 84 161 0 1456096288 252884688 18247008
06:02:54 941 8192000000 10398302032 10807402496 1195540416 12.735 100 97 183 1 1502844320 286522784 20781120
06:03:57 889 8192000000 10406648176 10809405440 1170897120 12.505 99 95 185 0 1512894288 319902112 22095056
06:05:01 1536 8192000000 10428278784 10868457472 1180562784 12.595 100 96 186 2 1545201216 343067120 21571312
06:06:05 1300 8192000000 10430903376 10900332544 1186798368 12.654 100 97 184 0 1538713552 330126224 21788960
06:07:08 1481 8192000000 10427573360 10898919424 1193271280 12.714 100 98 193 1 1534916688 318368352 23277056
06:08:11 2425 8192000000 10358959904 10800529408 1161923104 12.421 99 82 147 4 1469164160 290863568 16377488
06:09:18 1393 8192000000 10385215344 10800914432 1153133616 12.339 99 93 184 0 1491501216 316867856 21499744
06:10:25 1652 8192000000 10434388368 10846224384 1167462304 12.473 100 81 162 2 1537150368 349226000 20462064
06:11:32 909 8192000000 10486536304 10898395136 1175054848 12.544 100 96 186 0 1588194224 391496272 21643104
06:12:37 1592 8192000000 10525182880 10934099968 1182799840 12.616 100 92 174 0 1624201568 420258512 21143216
06:13:41 1099 8192000000 10558844096 10999205888 1175272208 12.546 100 82 162 0 1660445904 464932208 20241488
06:14:45 1179 8192000000 10565834272 11020234752 1178047072 12.572 100 79 157 0 1668448272 471086208 19314992
06:15:54 6077 8192000000 10589816352 11045072896 1183906880 12.627 100 71 141 0 1687270240 485103792 18259568
06:17:05 8838 8192000000 10571297840 11062370304 1183210608 12.620 100 56 112 0 1668524320 470749056 14564656
06:18:16 4782 8192000000 10550633616 11023196160 1169904720 12.496 99 47 94 2 1652137264 469747136 12485408
06:19:40 11502 8192000000 10454266976 10960158720 1148722192 12.298 100 24 48 2 1558566928 402844672 7000064
06:20:54 36187 8192000000 10365198128 10816401408 1126547584 12.089 99 28 55 0 1437115968 303513648 7054736
06:22:46 64325 8192000000 10257878576 10710491136 1103026096 11.866 96 21 42 2 1289457120 181743744 4687280
06:25:42 10594 8192000000 10100866192 10546458624 1138013824 12.197 100 59 102 0 1213142688 64201008 10927856
06:26:54 74241 8192000000 10108473616 10560872448 1093139408 11.772 96 19 38 1 1162295856 65116352 4040096
06:29:33 74519 8192000000 10058995216 10480226304 1098803904 11.826 97 29 55 3 1136567088 30709312 7053872
06:31:50 99499 8192000000 10079718272 10516508672 1123902160 12.064 99 39 76 1 1156813280 24879664 8031456
06:34:55 44472 8192000000 10111511616 10537439232 1135720112 12.175 99 46 89 0 1197863792 53190880 8952800
06:36:44 32963 8192000000 10074240960 10522894336 1150379040 12.313 100 29 56 2 1207977424 51790304 5808080
06:38:18 42597 8192000000 10123335632 10569719808 1135410352 12.172 98 21 41 0 1189330080 48015408 5904320
06:40:06 33751 8192000000 10181124016 10643361792 1164156224 12.442 100 43 73 3 1277966080 105122064 8687792
06:41:42 105824 8192000000 10159140400 10664030208 1137016112 12.187 98 19 37 3 1256392304 115517552 3858640
06:44:37 52029 8192000000 10091708640 10568740864 1129151872 12.113 98 18 36 1 1172576672 38889664 4535136
06:46:29 54152 8192000000 10077644976 10553634816 1138085200 12.198 99 13 26 0 1193534672 52726592 2722880
06:48:36 37379 8192000000 10086366480 10583613440 1134815760 12.167 99 5 10 0 1167454128 31719680 918688
06:50:13 48100 8192000000 10104629616 10595135488 1145064032 12.263 100 14 29 0 1197075536 48948928 3062576
06:52:08 45336 8192000000 10134572352 10584797184 1101706640 11.854 96 29 58 0 1202814064 95299392 5808032
06:53:59 86995 8192000000 10078553456 10552467456 1078242880 11.631 95 20 40 2 1196022352 112689328 5090144
06:56:27 77405 8192000000 10073179824 10593411072 1144558016 12.258 100 25 48 2 1224712000 75498048 4655936
06:58:44 45218 8192000000 10139999200 10584854528 1124567840 12.070 98 28 56 2 1218322768 88240800 5514128
07:01:01 73302 8192000000 10106727968 10584322048 1072895424 11.580 94 21 40 0 1115104224 38447248 3761552
07:03:37 109747 8192000000 10085182992 10527641600 1136697232 12.184 100 49 96 0 1219312960 72191072 10424656
07:06:33 9577 8192000000 10070896688 10503327744 1120140400 12.028 99 42 82 0 1176799552 48515680 8143472
07:07:44 43407 8192000000 10081496480 10547568640 1119209536 12.020 99 28 56 1 1164150992 39446928 5494528
07:09:30 83605 8192000000 10124301504 10575269888 1095579296 11.796 96 21 38 1 1168176272 68256240 4340736
07:11:54 72555 8192000000 10088095184 10594545664 1126881792 12.092 100 11 22 0 1221690800 92051632 2757376
07:14:20 76969 8192000000 10072577424 10548400128 1124871696 12.073 99 11 22 0 1191708448 63801200 3035552
07:16:40 52152 8192000000 10082141424 10545582080 1124558096 12.070 99 36 70 0 1181876560 49855024 7463440
07:18:35 32805 8192000000 10104094464 10537508864 1113703680 11.967 98 35 70 0 1190534240 69738784 7091776
07:20:09 34159 8192000000 10097243424 10527174656 1096094464 11.801 97 35 68 3 1145455856 42863728 6497664
07:21:45 75045 8192000000 10078680272 10523492352 1138287040 12.199 100 27 50 0 1196145264 52506864 5351360
07:24:01 39214 8192000000 10153056848 10634989568 1134229984 12.161 99 26 52 1 1241258000 101443120 5584896
07:25:42 42891 8192000000 10097436832 10537398272 1126474832 12.088 99 31 60 1 1173298032 40864032 5959168
07:27:36 54276 8192000000 10044145008 10502979584 1132675824 12.147 99 17 34 0 1166919024 30817744 3425456
07:29:43 29669 8192000000 10098647888 10541993984 1127953760 12.102 99 39 76 1 1189041744 53563184 7524800
07:31:23 68694 8192000000 10045868496 10518589440 1097705808 11.816 97 12 24 2 1135934048 35736336 2491904
07:33:34 72196 8192000000 10116169248 10573557760 1130756256 12.128 99 18 36 1 1213419648 78851616 3811776
07:36:02 78158 8192000000 10160691424 10632966144 1132096240 12.141 98 22 45 0 1237764208 100556176 5111792
07:38:25 43883 8192000000 10174976064 10655436800 1125878512 12.082 97 24 48 1 1266849408 134994912 5975984
07:40:09 58944 8192000000 10145334624 10640650240 1128030224 12.103 98 29 57 1 1275623840 140856560 6737056
07:42:16 32204 8192000000 10198079376 10646364160 1159417648 12.398 100 42 83 0 1295641216 127136880 9086688
07:43:57 62742 8192000000 10240467728 10689294336 1136814672 12.186 98 38 76 0 1302127392 157099616 8213104
07:46:01 42969 8192000000 9178611296 9475612672 253204608 2.998 21 16 31 0 393154064 135948880 4000576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-05-2022 07:48:34 to 01-05-2022 07:48:36.

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          215411
MON$OLDEST_ACTIVE               224209
MON$OLDEST_SNAPSHOT             224209
MON$NEXT_TRANSACTION            224209
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                       2109888
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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    Sun May  1 07:47:21 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

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