Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 10:37
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 15771768 kB
MemAvailable 31200476 kB
Buffers 0 kB
Cached 15900712 kB
SwapCached 2500 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-06-23 10:37:15
DTS_END                         2022-06-23 10:37:15.3360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60140
TRN_ID                          219108
ATT_ID                          581
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: 8985.94 28728 1078313
customer order: creation 169.98 483 20398
customer order: refuse 14.64 482 1757
order to supplier: creation 45.48 1475 5457
order to supplier: removal 3.42 184866 410
invoice (draft): creation 45.48 3336 5458
invoice (draft): removal 49.29 1108 5915
invoice accept: apply 42.29 29896 5075
invoice accept: cancel 44.65 1503 5358
customer reserve: creation 3930.37 284 471644
customer reserve: removal 4336.69 112 520403
realization accept: apply 84.47 303 10136
realization accept: cancel 88.98 190 10678
payment from customer: creation 59.75 189 7170
payment from customer: removal 5.81 152 697
payment to supplier: creation 48.54 209 5825
payment to supplier: removal 3.59 176 431
service: total inventory turnovers 3.72 4926 446
service: total monetary turnovers 7.73 467 927
service: aggregate perf. data 1.03 14352 123
service: refresh index statistics 0.04 330048 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-06-2022 10:38:15 to 23-06-2022 10:38:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8675
TEST_TIME 2 6799
TEST_TIME 3 6038
TEST_TIME 4 6364
TEST_TIME 5 5844
TEST_TIME 6 5514
TEST_TIME 7 5510
TEST_TIME 8 5354
TEST_TIME 9 5648
TEST_TIME 10 5987
TEST_TIME 11 5958
TEST_TIME 12 6670
TEST_TIME 13 6773
TEST_TIME 14 6537
TEST_TIME 15 6414
TEST_TIME 16 6552
TEST_TIME 17 6678
TEST_TIME 18 6850
TEST_TIME 19 6923
TEST_TIME 20 6947
TEST_TIME 21 7092
TEST_TIME 22 7073
TEST_TIME 23 7146
TEST_TIME 24 7151
TEST_TIME 25 7337
TEST_TIME 26 7219
TEST_TIME 27 7414
TEST_TIME 28 7572
TEST_TIME 29 8070
TEST_TIME 30 8030
TEST_TIME 31 8446
TEST_TIME 32 8476
TEST_TIME 33 8564
TEST_TIME 34 8744
TEST_TIME 35 9188
TEST_TIME 36 9315
TEST_TIME 37 9472
TEST_TIME 38 9775
TEST_TIME 39 9916
TEST_TIME 40 10475
TEST_TIME 41 10499
TEST_TIME 42 10391
TEST_TIME 43 10251
TEST_TIME 44 10219
TEST_TIME 45 10134
TEST_TIME 46 10051
TEST_TIME 47 9855
TEST_TIME 48 9828
TEST_TIME 49 9909
TEST_TIME 50 9892
TEST_TIME 51 9780
TEST_TIME 52 9696
TEST_TIME 53 9684
TEST_TIME 54 9704
TEST_TIME 55 9586
TEST_TIME 56 9659
TEST_TIME 57 9693
TEST_TIME 58 9581
TEST_TIME 59 9585
TEST_TIME 60 9628
TEST_TIME 61 9611
TEST_TIME 62 9516
TEST_TIME 63 9447
TEST_TIME 64 9439
TEST_TIME 65 9364
TEST_TIME 66 9329
TEST_TIME 67 9343
TEST_TIME 68 9245
TEST_TIME 69 9304
TEST_TIME 70 9268
TEST_TIME 71 9262
TEST_TIME 72 9233
TEST_TIME 73 9219
TEST_TIME 74 9316
TEST_TIME 75 9409
TEST_TIME 76 9422
TEST_TIME 77 9407
TEST_TIME 78 9442
TEST_TIME 79 9442
TEST_TIME 80 9402
TEST_TIME 81 9490
TEST_TIME 82 9413
TEST_TIME 83 9342
TEST_TIME 84 9315
TEST_TIME 85 9268
TEST_TIME 86 9235
TEST_TIME 87 9188
TEST_TIME 88 9131
TEST_TIME 89 9107
TEST_TIME 90 9117
TEST_TIME 91 9109
TEST_TIME 92 9100
TEST_TIME 93 9056
TEST_TIME 94 9055
TEST_TIME 95 9039
TEST_TIME 96 9039
TEST_TIME 97 9086
TEST_TIME 98 9124
TEST_TIME 99 9115
TEST_TIME 100 9130
TEST_TIME 101 9145
TEST_TIME 102 9203
TEST_TIME 103 9251
TEST_TIME 104 9188
TEST_TIME 105 9150
TEST_TIME 106 9119
TEST_TIME 107 9083
TEST_TIME 108 9026
TEST_TIME 109 9025
TEST_TIME 110 9022
TEST_TIME 111 9050
TEST_TIME 112 9029
TEST_TIME 113 9024
TEST_TIME 114 9026
TEST_TIME 115 9049
TEST_TIME 116 9011
TEST_TIME 117 9068
TEST_TIME 118 9070
TEST_TIME 119 9052
TEST_TIME 120 9008

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 10:38:19 to 23-06-2022 10:38:22.

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 1510995 1510995 0 0.00 0 19853 16 0 0
doc_list_biud 1510989 1510989 0 0.00 0 40360 45 0 0
sp_add_invoice_to_stock 5219 5075 144 2.75 64 222200 29897 0 144
sp_cancel_adding_invoice 5358 5358 0 0.00 7 89339 1504 0 0
sp_cancel_client_order 1757 1757 0 0.00 6 10039 483 0 0
sp_cancel_customer_reserve 520403 520403 0 0.00 1 31169 113 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 6066 153 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 9114 177 0 0
sp_cancel_supplier_invoice 5923 5915 8 0.13 3 30268 1109 0 8
sp_cancel_supplier_order 410 410 0 0.00 12 1726177 184867 0 0
sp_cancel_write_off 10678 10678 0 0.00 2 8465 191 0 0
sp_client_order 20398 20398 0 0.00 6 24169 483 0 0
sp_customer_reserve 471644 471644 0 0.00 1 81659 284 0 0
sp_fill_shopping_cart 31299 31299 0 0.00 1 12316 89 0 0
sp_fill_shopping_cart_clo_res 471612 471612 0 0.00 0 14926 20 0 0
sp_get_clo_for_invoice 5042 5042 0 0.00 0 16199 270 0 0
sp_kill_cost_storno 17164 17164 0 0.00 0 9107 91 0 0
sp_kill_qstorno_ret_qs2qd 1057809 1057809 0 0.00 0 31161 49 0 0
sp_kill_qty_storno 559694 559694 0 0.00 0 40358 113 0 0
sp_lock_selected_doc 20918 20918 0 0.00 0 10390 9 0 0
sp_make_cost_storno 28173 28173 0 0.00 0 19833 90 0 0
sp_multiply_rows_for_pdistr 28171 28171 0 0.00 0 9381 41 0 0
sp_multiply_rows_for_qdistr 20398 20398 0 0.00 0 9863 185 0 0
sp_pay_from_customer 7170 7170 0 0.00 5 12897 189 0 0
sp_pay_to_supplier 5825 5825 0 0.00 6 10046 210 0 0
sp_payment_common 12995 12995 0 0.00 2 8305 53 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 9978 396 0 0
sp_qd_handle_on_invoice_upd_sts 10396 10396 0 0.00 0 40355 633 0 0
sp_qd_handle_on_reserve_upd_sts 10135 10135 0 0.00 0 11507 61 0 0
sp_reserve_write_off 10136 10136 0 0.00 9 18537 303 0 0
sp_supplier_invoice 5458 5458 0 0.00 10 123380 3336 0 0
sp_supplier_order 5457 5457 0 0.00 8 26420 1476 0 0
srv_aggregate_perf_data 123 123 0 0.00 2290 127811 14352 0 0
srv_fill_mon_cache_memory 77 77 0 0.00 732 137086 27673 0 0
srv_find_qd_qs_mism 1028286 1028286 0 0.00 0 14505 13 0 0
srv_make_invnt_saldo 446 446 0 0.00 68 87240 4927 0 0
srv_make_money_saldo 927 927 0 0.00 1 14008 467 0 0
srv_recalc_idx_stat 5 5 0 0.00 223859 478876 330049 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5081 5081 0 0.00 2 10659 1092 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 2 5407 545 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 4432 33 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 6976 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 6849 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 3627 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1779 35 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 117273 9192 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 9466 28 0 0
t$perf-norm:sp_client_order 20399 20399 0 0.00 0 8917 41 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 975 16 0 0
t$perf-norm:sp_pay_from_customer 7170 7170 0 0.00 0 6772 23 0 0
t$perf-norm:sp_pay_to_supplier 5825 5825 0 0.00 0 4763 21 0 0
t$perf-norm:sp_reserve_write_off 10136 10136 0 0.00 0 6833 26 0 0
t$perf-norm:sp_supplier_invoice 5458 5458 0 0.00 0 2965 37 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 4565 39 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1550 49 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 187 8 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 15016 59 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 13379 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 173 100 0 0
v_add_invoice_to_stock 10358 10070 288 2.78 0 8252 28 0 288
v_all_customers 37934 37934 0 0.00 0 139 4 0 0
v_all_suppliers 30272 30272 0 0.00 0 98 5 0 0
v_all_wares 40794 40794 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1198 17 0 0
v_cancel_client_order 3514 3514 0 0.00 0 4417 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 2412 14 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2546 14 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 671 13 0 0
v_cancel_supplier_invoice 824 808 16 1.94 0 8414 43 0 16
v_cancel_supplier_order 820 820 0 0.00 0 8444 25 0 0
v_cancel_write_off 1900 1900 0 0.00 0 1921 24 0 0
v_max_id_clo_ord 5455 5455 0 0.00 0 617 5 0 0
v_max_id_clo_res 998 998 0 0.00 0 5143 23 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 906 5 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 2877 10 0 0
v_max_non_paid_realizn 3504 3504 0 0.00 0 4237 11 0 0
v_min_id_clo_ord 5455 5455 0 0.00 0 6355 11 0 0
v_min_id_clo_res 998 998 0 0.00 0 3396 21 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 3990 8 0 0
v_min_non_paid_invoice 5825 5825 0 0.00 0 8295 19 0 0
v_min_non_paid_realizn 7170 7170 0 0.00 0 7262 19 0 0
v_our_firm 10192 10192 0 0.00 0 90 5 0 0
v_reserve_write_off 20270 20270 0 0.00 0 7596 11 0 0
x_lock_depdocs_on_canc_invoice 10869 10869 0 0.00 0 26993 146 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 2968 358 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 2 26411 1270 0 0
x_make_qstorno_1200_2000 5458 5458 0 0.00 4 123350 3113 0 0
x_make_qstorno_2100_3300 471644 471644 0 0.00 0 81658 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 10:38:23 to 23-06-2022 10:38:27.

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
08:07:19 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 21047 8192000000 9713781712 10013999104 795836720 8.854 100 81 162 0 822747696 7470272 19440704
08:09:46 28006 8192000000 9764667616 10070138880 837416000 9.274 100 90 180 0 871044864 12290896 21337968
08:11:19 14582 8192000000 9790309760 10109894656 861443072 9.515 100 85 170 0 900765232 19260480 20061680
08:12:37 7242 8192000000 9847840944 10182172672 909350576 9.991 100 73 146 2 961235584 35975168 15909840
08:13:48 7560 8192000000 9918208944 10257387520 948135808 10.373 100 92 182 0 1024945808 57015808 19794192
08:15:04 6028 8192000000 9941306368 10292895744 983869168 10.722 100 93 186 0 1057244000 54365504 19009328
08:16:14 2747 8192000000 9983454176 10343292928 1007671792 10.953 100 90 180 0 1104285264 77995792 18617680
08:17:19 1287 8192000000 9993533840 10362990592 1030326240 11.172 100 89 170 1 1113197680 65460384 17411056
08:18:23 1500 8192000000 10020219888 10388221952 1048814608 11.349 100 90 173 0 1140676224 75207136 16654480
08:19:28 790 8192000000 10022321200 10398089216 1057216448 11.430 100 93 183 1 1139313856 63196768 18900640
08:20:31 683 8192000000 10055741856 10431426560 1065198432 11.506 100 97 191 1 1174420800 90799200 18423168
08:21:36 1182 8192000000 10071230032 10452426752 1072495648 11.576 100 94 179 0 1190524832 99654656 18374528
08:22:39 1151 8192000000 10056262704 10437128192 1075564272 11.605 100 94 187 0 1179112128 84056576 19491280
08:23:41 1084 8192000000 10069949568 10455883776 1081685472 11.664 100 96 185 0 1192653200 91532400 19435328
08:24:42 1267 8192000000 10076473760 10466902016 1092244976 11.764 100 97 191 1 1196978896 85773008 18960912
08:25:45 4594 8192000000 10034271152 10434670592 1092252064 11.764 100 66 130 1 1164527920 58646752 13629104
08:26:52 1351 8192000000 10064932240 10454413312 1095301616 11.793 100 94 183 1 1187321376 72580496 19439264
08:27:54 1462 8192000000 10073459008 10461196288 1099835936 11.836 100 94 190 1 1197230192 78749328 18644928
08:28:56 1590 8192000000 10055253360 10449727488 1093468992 11.776 100 75 139 0 1188655968 81790544 13396432
08:29:59 3196 8192000000 10040759216 10437779456 1089762608 11.740 100 68 131 2 1163569792 60326480 13480704
08:31:04 7491 8192000000 10006779744 10405257216 1066238448 11.516 99 52 98 1 1123823760 48038112 9547200
08:32:15 984 8192000000 10032665504 10417397760 1079289200 11.641 100 85 164 2 1147218080 50407904 17520976
08:33:17 980 8192000000 10023744448 10404638720 1060039488 11.457 100 84 160 0 1145790288 69065728 16685072
08:34:19 1635 8192000000 10013683984 10393444352 1051987872 11.380 100 93 172 4 1141264800 71423472 17853456
08:35:22 1134 8192000000 10007567856 10385928192 1040933024 11.274 100 98 186 0 1130171344 69903600 19334720
08:36:24 1105 8192000000 10009625344 10382012416 1045128880 11.314 100 95 181 2 1130610144 67744448 17736816
08:37:28 1482 8192000000 10009705680 10385518592 1057992832 11.437 100 89 176 2 1133074960 57071440 18010688
08:38:35 9611 8192000000 10023161920 10411089920 1050676752 11.367 99 71 138 1 1140302784 75498832 14127200
08:39:52 774 8192000000 10036064384 10416246784 1067792096 11.531 100 80 149 2 1150724848 68338096 14594656
08:40:56 1229 8192000000 10071584832 10449428480 1070282944 11.555 100 86 164 2 1187715616 100369168 17063504
08:42:04 21215 8192000000 10045499408 10434600960 1089570160 11.739 100 63 122 1 1166112784 63351408 13191216
08:43:28 10480 8192000000 10107004352 10502692864 1109428528 11.927 100 69 133 0 1227803184 104484480 13890176
08:44:48 1088 8192000000 10141376064 10524958720 1097790016 11.817 99 94 180 3 1259587936 142860848 18937072
08:45:54 2875 8192000000 10162925088 10563031040 1101170048 11.849 100 74 142 1 1275980448 158983840 15826560
08:47:01 842 8192000000 10204023680 10596433920 1124688288 12.071 100 95 183 2 1319261056 174201200 20371568
08:48:06 3261 8192000000 10224832288 10621812736 1126788304 12.091 99 73 141 3 1340026608 196683296 16555008
08:49:16 1114 8192000000 10255082368 10676211712 1156684352 12.372 100 97 188 1 1366040608 188974784 20381472
08:50:21 1862 8192000000 10254775440 10652438528 1165316000 12.453 100 93 179 1 1365169824 180252176 19601648
08:51:30 1389 8192000000 10295330224 10694004736 1174220928 12.536 100 91 178 0 1404553456 210599504 19733024
08:52:43 3015 8192000000 10317107696 10721226752 1173028144 12.525 100 84 165 0 1429379616 236826800 19524672
08:53:52 1204 8192000000 10332803200 10758426624 1170081232 12.498 99 95 183 1 1444608880 253908928 20618720
08:55:01 1592 8192000000 10354907968 10771910656 1179972352 12.590 100 87 171 2 1459930832 259708400 20250080
08:56:05 1590 8192000000 10384090224 10792730624 1186527488 12.651 100 93 182 2 1492366240 284479200 21359552
08:57:11 1021 8192000000 10417918288 10826838016 1191244400 12.695 100 95 180 1 1522366080 310870528 20251152
08:58:14 716 8192000000 10429357360 10850197504 1181280032 12.602 100 96 193 0 1540488656 337158800 22049824
08:59:18 800 8192000000 10474626624 10877607936 1182912288 12.617 100 94 177 0 1581178800 377219424 21047088
09:00:23 749 8192000000 10509325296 10927796224 1192409840 12.706 100 97 191 0 1614069232 397679488 23979904
09:01:27 2165 8192000000 10519229424 10940583936 1196315472 12.742 100 93 174 1 1626200080 410068688 19815920
09:02:35 1706 8192000000 10550550224 10989969408 1197358816 12.752 100 98 190 2 1657676032 437302000 23015216
09:03:41 1100 8192000000 10576330800 11005571072 1196827072 12.747 100 94 183 1 1677725984 459026832 21872080
09:04:45 1542 8192000000 10545197904 11017187328 1192308864 12.705 100 93 181 0 1658535808 444333424 21893520
09:05:50 795 8192000000 10547074960 11054538752 1202298176 12.798 100 95 185 0 1646537776 422573344 21666256
09:06:52 6684 8192000000 10531758176 10998886400 1202172464 12.797 100 58 114 1 1634278352 417422304 14683584
09:08:04 898 8192000000 10504819152 10982301696 1179074080 12.582 100 90 175 1 1608297712 408381968 20841664
09:09:07 6438 8192000000 10455575936 10905436160 1166346592 12.463 100 51 101 0 1561237648 382262064 12628992
09:10:18 4789 8192000000 10465692832 10912444416 1157104448 12.376 100 55 108 1 1566186992 395799472 13283072
09:11:26 8618 8192000000 10402607024 10915454976 1171748608 12.513 100 63 123 1 1506687504 320249520 14689376
09:12:36 13106 8192000000 10390844848 10847051776 1168335680 12.481 100 35 70 1 1493077888 315970704 8771504
09:14:34 39373 8192000000 10314785616 10790076416 1139578528 12.212 98 26 52 1 1399805040 253698624 6527888
09:16:15 53849 8192000000 10153734976 10689630208 1116044544 11.990 98 23 44 0 1308625152 187179504 5401104
09:18:09 98931 8192000000 10084465680 10544959488 1074869856 11.599 95 4 8 0 1127335424 51695168 770400
09:20:53 10038 8192000000 10071964304 10532761600 1133774224 12.157 100 56 100 3 1179091024 34291584 11025216
09:22:05 21070 8192000000 10079524912 10507362304 1101388128 11.851 98 34 68 2 1164517168 56647792 6481248
09:23:33 137086 8192000000 10134010912 10612932608 1111103200 11.943 98 24 48 1 1213118256 96454576 5560480
09:26:57 43177 8192000000 10065103136 10509676544 1105560320 11.890 97 30 60 1 1144186656 32490048 6136288
09:28:57 20706 8192000000 10063532240 10494119936 1102789824 11.864 98 35 67 2 1149916832 40558320 6568688
09:30:21 11564 8192000000 10152398512 10575777792 1141477744 12.229 100 63 124 1 1249966480 95072608 13416128
09:31:49 19956 8192000000 10095636464 10528305152 1102967312 11.866 97 43 79 2 1180058448 67075184 10015952
09:33:13 13186 8192000000 10135370224 10583941120 1119615952 12.023 99 53 100 1 1228903120 97250992 12036176
09:34:29 77452 8192000000 10109792128 10549682176 1103532384 11.871 98 16 32 1 1189930944 82919824 3478736
09:36:47 94267 8192000000 10059163840 10497220608 1083302208 11.679 96 40 63 2 1129643776 37234464 9107104
09:39:28 101653 8192000000 10083096576 10546978816 1131859072 12.139 100 19 38 0 1184775856 48779760 4137024
09:42:11 37170 8192000000 10087378352 10560086016 1134939776 12.168 100 19 38 0 1208024128 68471136 4613216
09:43:49 68819 8192000000 10110443984 10603290624 1131586528 12.136 99 15 30 1 1219012480 84194784 3231168
09:46:28 40399 8192000000 10093146672 10576818176 1139787136 12.214 100 30 58 0 1188859760 42765600 6307024
09:48:09 28425 8192000000 10126255104 10588028928 1147451728 12.286 100 31 61 2 1228972864 75523152 5997984
09:49:41 40880 8192000000 10162669408 10618912768 1135837232 12.176 99 24 48 1 1250810912 109794448 5179232
09:51:22 17788 8192000000 10158720848 10600837120 1147917936 12.290 100 29 57 1 1262444928 108495136 6031856
09:52:42 25073 8192000000 10085047296 10532470784 1132790048 12.148 98 42 78 3 1181536000 40566176 8179776
09:54:07 15431 8192000000 10131467904 10559414272 1118932240 12.017 97 49 98 0 1206342144 78023440 9386464
09:55:25 16353 8192000000 10077548288 10510270464 1143493408 12.248 100 23 45 2 1189425888 41808464 4124016
09:56:43 36949 8192000000 10123044928 10579169280 1142000128 12.234 99 28 55 0 1215278608 67552768 5725712
09:58:29 29958 8192000000 10081373504 10546921472 1130105296 12.122 99 35 66 1 1186207920 48261792 7840832
10:00:01 80342 8192000000 10083459600 10553896960 1135964336 12.178 99 38 76 1 1206085472 62556144 7564992
10:02:23 21256 8192000000 10100194400 10541883392 1140101392 12.216 99 34 67 0 1195989008 48978400 6909216
10:04:52 47210 8192000000 10077986048 10534412288 1134505200 12.164 100 13 26 0 1175087520 37791184 2791136
10:06:39 62524 8192000000 10100367616 10598719488 1130670480 12.128 99 15 30 1 1193674512 59232416 3771616
10:08:59 51884 8192000000 10040679536 10493194240 1107354848 11.907 98 15 30 0 1138190496 27997264 2838384
10:10:51 38231 8192000000 10095986256 10544250880 1097300000 11.812 97 26 52 0 1159559248 56683792 5575456
10:12:35 33738 8192000000 10121261680 10566303744 1110922336 11.941 98 28 56 1 1188193264 71376976 5893952
10:14:14 44203 8192000000 10099807664 10562433024 1114100592 11.971 98 36 72 0 1225379536 103793216 7485728
10:16:02 46639 8192000000 10117498416 10559279104 1089817984 11.741 96 32 64 0 1160662592 64569312 6275296
10:17:55 32830 8192000000 10153671104 10579456000 1132262080 12.143 100 46 92 2 1236980480 94947088 9771312
10:19:28 33308 8192000000 10080408368 10505416704 1095794224 11.798 97 29 53 1 1151513056 50666528 5052304
10:21:05 89096 8192000000 10059395376 10517651456 1114358848 11.974 100 30 60 1 1180446224 60358832 5728544
10:23:34 50871 8192000000 10035143632 10505490432 1071294032 11.564 96 16 32 1 1119579280 45326064 2959184
10:25:25 59334 8192000000 10070069168 10539831296 1112337472 11.955 99 13 26 0 1185377168 70007504 3032192
10:27:32 62971 8192000000 10072665408 10549043200 1109078288 11.924 98 13 26 0 1179696800 68099296 2519216
10:29:44 22283 8192000000 10106339696 10542206976 1122980096 12.055 99 40 80 0 1207014704 75690432 8344176
10:31:06 31323 8192000000 10140357600 10590523392 1132806688 12.148 100 40 80 1 1248426464 107242400 8377376
10:32:45 71482 8192000000 10140714096 10594291712 1133966016 12.159 99 23 46 0 1232573840 93404112 5203712
10:35:01 14572 8192000000 10107329712 10531061760 1134822880 12.167 100 62 113 0 1200628288 54518144 11287264
10:36:16 21051 8192000000 10121798256 10552336384 1116579168 11.995 98 32 64 0 1189231104 66258192 6393744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-06-2022 10:38:29 to 23-06-2022 10:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-06-2022 10:38:47 to 23-06-2022 10:38:48.

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          215309
MON$OLDEST_ACTIVE               219250
MON$OLDEST_SNAPSHOT             219250
MON$NEXT_TRANSACTION            219250
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                       2086368
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Thu Jun 23 10:37:21 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

23.06.22 10:38:50. 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-06-23 10:38:52 No severe PSQL-related problems occured
23.06.22 10:38:53. End of report.