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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14951184 kB
MemAvailable 30414684 kB
Buffers 20 kB
Cached 16710052 kB
SwapCached 3140 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-02-21 04:37:26
DTS_END                         2023-02-21 04:37:26.2660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42306
TRN_ID                          222292
ATT_ID                          675
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: 9011.59 22737 1081391
customer order: creation 177.47 432 21296
customer order: refuse 14.78 462 1774
order to supplier: creation 46.13 1335 5535
order to supplier: removal 3.17 169612 380
invoice (draft): creation 46.45 2983 5574
invoice (draft): removal 48.50 986 5820
invoice accept: apply 43.31 28665 5197
invoice accept: cancel 44.32 1301 5318
customer reserve: creation 4023.78 275 482854
customer reserve: removal 4266.25 99 511950
realization accept: apply 84.13 279 10096
realization accept: cancel 80.83 184 9700
payment from customer: creation 60.08 178 7209
payment from customer: removal 5.79 121 695
payment to supplier: creation 49.81 193 5977
payment to supplier: removal 3.72 126 446
service: total inventory turnovers 4.38 3776 525
service: total monetary turnovers 7.63 312 916
service: aggregate perf. data 1.04 14735 125
service: refresh index statistics 0.03 228679 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-02-2023 04:39:56 to 21-02-2023 04:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5978
TEST_TIME 2 4858
TEST_TIME 3 5188
TEST_TIME 4 4996
TEST_TIME 5 4894
TEST_TIME 6 4939
TEST_TIME 7 4958
TEST_TIME 8 5055
TEST_TIME 9 5099
TEST_TIME 10 5130
TEST_TIME 11 5526
TEST_TIME 12 5864
TEST_TIME 13 5971
TEST_TIME 14 6041
TEST_TIME 15 6513
TEST_TIME 16 6753
TEST_TIME 17 6823
TEST_TIME 18 6820
TEST_TIME 19 7051
TEST_TIME 20 7183
TEST_TIME 21 7303
TEST_TIME 22 7438
TEST_TIME 23 7774
TEST_TIME 24 8046
TEST_TIME 25 8196
TEST_TIME 26 8420
TEST_TIME 27 8472
TEST_TIME 28 8918
TEST_TIME 29 9250
TEST_TIME 30 9313
TEST_TIME 31 9513
TEST_TIME 32 9347
TEST_TIME 33 9341
TEST_TIME 34 9688
TEST_TIME 35 10027
TEST_TIME 36 9915
TEST_TIME 37 9915
TEST_TIME 38 9925
TEST_TIME 39 10517
TEST_TIME 40 10496
TEST_TIME 41 10406
TEST_TIME 42 10321
TEST_TIME 43 10109
TEST_TIME 44 10055
TEST_TIME 45 9988
TEST_TIME 46 9895
TEST_TIME 47 9825
TEST_TIME 48 9759
TEST_TIME 49 9712
TEST_TIME 50 9579
TEST_TIME 51 9533
TEST_TIME 52 9535
TEST_TIME 53 9488
TEST_TIME 54 9565
TEST_TIME 55 9653
TEST_TIME 56 9589
TEST_TIME 57 9559
TEST_TIME 58 9506
TEST_TIME 59 9526
TEST_TIME 60 9468
TEST_TIME 61 9405
TEST_TIME 62 9388
TEST_TIME 63 9251
TEST_TIME 64 9342
TEST_TIME 65 9315
TEST_TIME 66 9343
TEST_TIME 67 9293
TEST_TIME 68 9288
TEST_TIME 69 9311
TEST_TIME 70 9337
TEST_TIME 71 9394
TEST_TIME 72 9407
TEST_TIME 73 9410
TEST_TIME 74 9507
TEST_TIME 75 9435
TEST_TIME 76 9491
TEST_TIME 77 9471
TEST_TIME 78 9450
TEST_TIME 79 9362
TEST_TIME 80 9290
TEST_TIME 81 9266
TEST_TIME 82 9197
TEST_TIME 83 9176
TEST_TIME 84 9115
TEST_TIME 85 9179
TEST_TIME 86 9165
TEST_TIME 87 9119
TEST_TIME 88 9133
TEST_TIME 89 9119
TEST_TIME 90 9197
TEST_TIME 91 9186
TEST_TIME 92 9185
TEST_TIME 93 9207
TEST_TIME 94 9255
TEST_TIME 95 9275
TEST_TIME 96 9283
TEST_TIME 97 9267
TEST_TIME 98 9222
TEST_TIME 99 9207
TEST_TIME 100 9188
TEST_TIME 101 9149
TEST_TIME 102 9121
TEST_TIME 103 9099
TEST_TIME 104 9058
TEST_TIME 105 9021
TEST_TIME 106 9010
TEST_TIME 107 8975
TEST_TIME 108 8977
TEST_TIME 109 8982
TEST_TIME 110 8977
TEST_TIME 111 8994
TEST_TIME 112 8979
TEST_TIME 113 9077
TEST_TIME 114 9048
TEST_TIME 115 9040
TEST_TIME 116 9020
TEST_TIME 117 9077
TEST_TIME 118 9167
TEST_TIME 119 9096
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-02-2023 04:40:00 to 21-02-2023 04:40:03.

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 1524995 1524995 0 0.00 0 19588 15 0 0
doc_list_biud 1524983 1524983 0 0.00 0 26627 39 0 0
sp_add_invoice_to_stock 5324 5197 127 2.38 33 203752 28666 0 127
sp_cancel_adding_invoice 5318 5318 0 0.00 6 73222 1301 0 0
sp_cancel_client_order 1774 1774 0 0.00 6 10180 463 0 0
sp_cancel_customer_reserve 511950 511950 0 0.00 1 26646 100 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 3942 121 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 7174 127 0 0
sp_cancel_supplier_invoice 5838 5820 18 0.30 2 11762 986 0 18
sp_cancel_supplier_order 380 380 0 0.00 10 1290661 169613 0 0
sp_cancel_write_off 9700 9700 0 0.00 2 13105 184 0 0
sp_client_order 21296 21296 0 0.00 5 13241 433 0 0
sp_customer_reserve 482854 482854 0 0.00 1 141372 275 0 0
sp_fill_shopping_cart 32391 32391 0 0.00 1 13504 82 0 0
sp_fill_shopping_cart_clo_res 482820 482820 0 0.00 0 18527 19 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 33561 264 0 0
sp_kill_cost_storno 16159 16159 0 0.00 0 7163 80 0 0
sp_kill_qstorno_ret_qs2qd 1039800 1039800 0 0.00 0 26612 43 0 0
sp_kill_qty_storno 550186 550186 0 0.00 0 26623 100 0 0
sp_lock_selected_doc 21186 21186 0 0.00 0 9780 8 0 0
sp_make_cost_storno 28431 28431 0 0.00 0 17989 83 0 0
sp_multiply_rows_for_pdistr 28431 28431 0 0.00 0 9194 38 0 0
sp_multiply_rows_for_qdistr 21295 21295 0 0.00 0 11223 173 0 0
sp_pay_from_customer 7209 7209 0 0.00 6 14563 178 0 0
sp_pay_to_supplier 5977 5977 0 0.00 6 18771 193 0 0
sp_payment_common 13186 13186 0 0.00 2 14534 45 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 10154 390 0 0
sp_qd_handle_on_invoice_upd_sts 10467 10467 0 0.00 0 26535 513 0 0
sp_qd_handle_on_reserve_upd_sts 10095 10095 0 0.00 0 9362 62 0 0
sp_reserve_write_off 10096 10096 0 0.00 8 12601 280 0 0
sp_supplier_invoice 5574 5574 0 0.00 11 117819 2984 0 0
sp_supplier_order 5535 5535 0 0.00 6 36464 1336 0 0
srv_aggregate_perf_data 125 125 0 0.00 2459 174243 14736 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 840 125228 29930 0 0
srv_find_qd_qs_mism 1030441 1030441 0 0.00 0 9668 12 0 0
srv_make_invnt_saldo 525 525 0 0.00 38 59307 3777 0 0
srv_make_money_saldo 916 916 0 0.00 1 12266 313 0 0
srv_recalc_idx_stat 4 4 0 0.00 35089 423936 228679 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5201 5201 0 0.00 1 15331 900 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 2 6856 488 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 1676 30 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 877 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 4123 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 127 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 1530 44 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 93221 7707 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 2152 25 0 0
t$perf-norm:sp_client_order 21296 21296 0 0.00 0 21155 38 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 1439 16 0 0
t$perf-norm:sp_pay_from_customer 7209 7209 0 0.00 0 5216 20 0 0
t$perf-norm:sp_pay_to_supplier 5977 5977 0 0.00 0 5056 20 0 0
t$perf-norm:sp_reserve_write_off 10096 10096 0 0.00 0 7375 24 0 0
t$perf-norm:sp_supplier_invoice 5574 5574 0 0.00 0 3659 28 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 4006 31 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 301 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 136 5 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 973 20 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 5264 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 169 93 0 0
v_add_invoice_to_stock 10544 10290 254 2.40 0 7942 26 0 254
v_all_customers 39218 39218 0 0.00 0 121 4 0 0
v_all_suppliers 31024 31024 0 0.00 0 116 4 0 0
v_all_wares 42588 42588 0 0.00 0 157 4 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1257 17 0 0
v_cancel_client_order 3548 3548 0 0.00 0 4043 12 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2284 14 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 1 1562 12 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 1027 12 0 0
v_cancel_supplier_invoice 928 892 36 3.87 0 3245 25 0 36
v_cancel_supplier_order 760 760 0 0.00 0 499 10 0 0
v_cancel_write_off 2082 2082 0 0.00 0 1598 23 0 0
v_max_id_clo_ord 5532 5532 0 0.00 0 230 5 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 2957 25 0 0
v_max_id_ord_sup 5563 5563 0 0.00 0 3910 7 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 710 8 0 0
v_max_non_paid_realizn 3623 3623 0 0.00 0 3478 9 0 0
v_min_id_clo_ord 5532 5532 0 0.00 0 7818 10 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 12985 35 0 0
v_min_id_ord_sup 5563 5563 0 0.00 0 8366 12 0 0
v_min_non_paid_invoice 5977 5977 0 0.00 0 3737 11 0 0
v_min_non_paid_realizn 7209 7209 0 0.00 0 14528 19 0 0
v_our_firm 10542 10542 0 0.00 0 65 5 0 0
v_reserve_write_off 20190 20190 0 0.00 0 3071 11 0 0
x_lock_depdocs_on_canc_invoice 10692 10692 0 0.00 0 32384 124 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 7514 414 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 2 36455 1163 0 0
x_make_qstorno_1200_2000 5573 5573 0 0.00 4 112766 2762 0 0
x_make_qstorno_2100_3300 482853 482853 0 0.00 0 141365 243 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2023 04:40:04 to 21-02-2023 04:40:08.

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 9 8192000000 8810854080 8826646528 13050832 0.159 7 2 3 0 13211728 99536 61360
02:08:30 28011 8192000000 9718570224 10019426304 794463040 8.840 100 88 175 0 823289888 7002320 21824528
02:10:05 9498 8192000000 9754019184 10060791808 833225568 9.232 100 87 174 0 866032720 11240576 21566576
02:11:16 17909 8192000000 9790908000 10109480960 858546976 9.486 100 88 174 0 898679696 19339168 20793552
02:12:40 9844 8192000000 9848873568 10178564096 906232304 9.960 100 81 159 1 962717520 38805280 17679936
02:13:56 4502 8192000000 9901375600 10244087808 950030336 10.391 100 78 156 0 1013350032 46524592 16795104
02:15:05 2538 8192000000 9958560720 10310852608 989772768 10.779 100 88 175 0 1070406720 62780720 17853232
02:16:10 3786 8192000000 9995374992 10356408320 1015972000 11.033 100 88 176 0 1112992528 79208656 17811872
02:17:20 1576 8192000000 10038785760 10406883328 1038949920 11.255 100 91 176 0 1153312736 96542416 17820400
02:18:24 838 8192000000 10013860848 10385719296 1051162768 11.372 100 94 178 2 1136856528 67259632 18434128
02:19:27 980 8192000000 10049240960 10427269120 1061761136 11.473 100 96 177 0 1167490576 87596752 18132688
02:20:29 12493 8192000000 10045346672 10418114560 1070782240 11.560 100 94 185 4 1167547296 78152736 18612320
02:21:43 970 8192000000 10059536416 10440011776 1074972112 11.600 100 95 185 1 1183443344 89760336 18710896
02:22:46 1162 8192000000 10064332896 10449948672 1077323360 11.622 100 96 183 1 1185950240 90751168 17875712
02:23:49 939 8192000000 10036604080 10419236864 1081595248 11.663 100 94 177 0 1159176192 58384480 19196464
02:24:52 1141 8192000000 10062635296 10450604032 1085766176 11.702 100 98 185 1 1189427376 83304384 20356816
02:25:55 1538 8192000000 10046830928 10429648896 1087453472 11.718 100 88 162 2 1164107536 61279328 15374736
02:26:57 6023 8192000000 10027979184 10419191808 1082002480 11.667 99 60 108 2 1148496224 55781744 10712000
02:28:04 2153 8192000000 10041194224 10430996480 1082783584 11.674 99 85 166 2 1166424096 65371824 18268688
02:29:08 903 8192000000 10040533792 10427002880 1085243328 11.697 100 89 172 0 1167258880 64517072 17498480
02:30:11 2362 8192000000 10051841488 10444902400 1089818080 11.741 100 81 150 0 1173135632 66398944 16918608
02:31:17 1255 8192000000 10032613984 10415300608 1067398288 11.527 100 98 192 2 1154513600 67408032 19707280
02:32:20 1263 8192000000 10040173648 10420813824 1074208928 11.592 100 97 181 2 1164331056 72365632 17756496
02:33:22 856 8192000000 10037600096 10420981760 1064078256 11.495 99 89 173 0 1161946928 80306000 17562672
02:34:25 1068 8192000000 10043794816 10419200000 1052271728 11.382 99 94 176 1 1165309120 95671408 17365984
02:35:27 1139 8192000000 10020220416 10395201536 1052495728 11.385 100 93 177 3 1144445376 73594448 18355200
02:36:29 1236 8192000000 10007705680 10378145792 1035714896 11.223 100 95 186 2 1133527568 79015536 18797136
02:37:36 2961 8192000000 9992918912 10368081920 1039401824 11.259 100 73 139 2 1112615408 59365392 13848192
02:38:47 6878 8192000000 10009424352 10391572480 1058174192 11.439 100 65 125 1 1124661232 54256112 12230928
02:40:00 4772 8192000000 10043697792 10429853696 1070618816 11.558 100 63 121 0 1166166016 83169392 12377808
02:41:12 3803 8192000000 10063689232 10442899456 1086166976 11.706 100 79 146 1 1182750288 81662048 14921264
02:42:21 6543 8192000000 10123968256 10518773760 1100378832 11.841 100 61 117 1 1244179424 131545200 12255392
02:43:32 5497 8192000000 10125054272 10517499904 1106508880 11.899 100 66 129 0 1244564240 124310384 13744976
02:44:41 1023 8192000000 10158030416 10552471552 1113950672 11.970 99 95 181 2 1271191984 135956240 21285072
02:45:46 1594 8192000000 10205035888 10598039552 1130810240 12.129 100 89 170 3 1319959568 170740304 18409024
02:46:54 7594 8192000000 10215950080 10610315264 1135461632 12.173 100 79 156 0 1330561488 177355280 17744576
02:48:15 1356 8192000000 10252543760 10658480128 1152686896 12.335 100 93 178 2 1365451216 191647120 21117200
02:49:20 2061 8192000000 10276130864 10673762304 1158294688 12.387 100 85 164 0 1394905600 218993056 17617856
02:50:28 2697 8192000000 10321094032 10723397632 1169271584 12.490 100 89 173 0 1434504880 245507536 19725760
02:51:34 1409 8192000000 10334067984 10744352768 1171523024 12.511 100 91 177 1 1445108672 252511040 21074608
02:52:39 2164 8192000000 10367043312 10787901440 1175154448 12.545 100 98 193 0 1470549296 273746304 21648544
02:53:43 3653 8192000000 10346426192 10775396352 1164723552 12.447 100 63 124 0 1456908432 277461216 14723664
02:54:54 2046 8192000000 10357936000 10763173888 1170713648 12.503 100 86 168 0 1466647344 276022016 19911680
02:56:03 1333 8192000000 10394633232 10836832256 1188738400 12.672 100 99 192 3 1503581856 291180512 23662944
02:57:07 830 8192000000 10424145632 10837561344 1209130480 12.861 100 97 190 0 1533481424 301753616 22597328
02:58:11 1173 8192000000 10448515968 10874986496 1207550240 12.846 100 95 187 0 1550681600 321171968 21959392
02:59:15 839 8192000000 10480714432 10899009536 1198638272 12.764 100 95 185 0 1587394592 367519312 21237008
03:00:18 1312 8192000000 10459164992 10928971776 1209839168 12.868 100 88 176 1 1567011008 336114832 21057008
03:01:21 2623 8192000000 10483803056 10914942976 1208363440 12.854 100 78 154 3 1588256976 361859488 18034048
03:02:26 9204 8192000000 10442310192 10912301056 1199605888 12.773 100 52 100 1 1563297744 351125200 12566656
03:03:44 6510 8192000000 10457871600 10899701760 1188534400 12.670 100 51 102 1 1564533648 363999904 11999344
03:04:52 10437 8192000000 10411666784 10911453184 1170349072 12.500 99 35 70 0 1542346064 362701472 9295520
03:06:10 53692 8192000000 10309382544 10773204992 1149577152 12.306 99 30 60 0 1421663920 264623280 7463488
03:08:05 5769 8192000000 10274068720 10738008064 1141076112 12.226 99 54 103 1 1367263792 214094144 12093536
03:09:13 2448 8192000000 10277494128 10696318976 1135996704 12.178 100 63 124 2 1378601088 228912912 13691472
03:10:23 3445 8192000000 10352921216 10784419840 1156221536 12.368 100 61 122 0 1468036272 297767040 14047696
03:11:29 6971 8192000000 10265978560 10795991040 1151789200 12.326 100 35 68 0 1370882096 210359200 8733696
03:12:37 35949 8192000000 10244258464 10735751168 1161202768 12.415 100 28 54 1 1372403472 204930192 6270512
03:14:20 23208 8192000000 10264940832 10723704832 1167341984 12.472 100 22 39 0 1376372112 203697808 5332320
03:16:01 50799 8192000000 10122664368 10632036352 1145514592 12.267 99 11 22 0 1284868720 136759072 2595056
03:18:01 59838 8192000000 10114638512 10553585664 1143140816 12.245 99 29 54 0 1219406768 71015056 5250896
03:21:06 125228 8192000000 10079468544 10529554432 1095696208 11.797 95 26 52 1 1138816224 37737952 5382064
03:24:26 64187 8192000000 10080405632 10515177472 1099619008 11.834 96 48 92 0 1141450000 31498288 10332704
03:26:31 106639 8192000000 10115763408 10541834240 1139591872 12.212 99 65 118 4 1200872144 49163728 12116544
03:29:18 6960 8192000000 10162929552 10592010240 1147499904 12.286 100 55 101 1 1257557216 98947968 11109344
03:30:33 3209 8192000000 10154692208 10584109056 1138296240 12.200 100 63 127 1 1251088256 99163008 13629008
03:31:50 47697 8192000000 10139850336 10612514816 1145001312 12.263 100 28 51 0 1254614160 103281200 6331648
03:33:41 9666 8192000000 10091532320 10584293376 1142379792 12.238 100 21 38 0 1200625008 54113440 4131776
03:34:51 41304 8192000000 10080469584 10538467328 1127610080 12.099 98 17 28 0 1180150752 49565648 2975024
03:36:49 50318 8192000000 10096089664 10593488896 1142023776 12.235 100 10 20 0 1202597120 58382224 2191120
03:38:46 53465 8192000000 10099096576 10571354112 1123797456 12.063 98 11 22 0 1173712752 47737152 2178144
03:40:43 60137 8192000000 10116272688 10659078144 1150314432 12.312 100 23 45 1 1235593040 80487568 4791040
03:42:56 61254 8192000000 10036982880 10478297088 1090060432 11.743 95 13 26 0 1115347232 22968064 2318736
03:44:58 7749 8192000000 10072944960 10501271552 1105178512 11.887 98 59 112 0 1155331088 39141744 11010832
03:46:08 15496 8192000000 10120911184 10554302464 1120050256 12.027 99 52 104 0 1208814352 78510768 10253328
03:47:32 25470 8192000000 10102393376 10540527616 1118045312 12.009 98 46 90 1 1215605408 88465008 9095088
03:49:02 23545 8192000000 10114611312 10588774400 1118565488 12.013 99 36 72 0 1201243600 75336352 7341760
03:50:28 102885 8192000000 10065815888 10521518080 1032741856 11.195 92 22 44 0 1124462080 87258176 4462048
03:53:11 15933 8192000000 10124472400 10559508480 1109436960 11.927 99 46 93 1 1216696768 97796672 9463136
03:54:37 31422 8192000000 10114996736 10546102272 1115950352 11.989 99 54 103 2 1186661664 60570560 10140752
03:56:09 79291 8192000000 10067625456 10509647872 1129676304 12.118 100 29 58 1 1180576336 44943296 5956736
03:58:29 42872 8192000000 10097249248 10570731520 1136543936 12.183 100 15 30 0 1199375792 59601568 3230288
04:00:12 38158 8192000000 10125322784 10568900608 1119067824 12.018 98 19 38 0 1199840272 76890032 3882416
04:02:02 75363 8192000000 10076130224 10522603520 1102719040 11.863 97 16 31 0 1147344880 40937520 3688320
04:04:20 19683 8192000000 10106002304 10536591360 1100968320 11.847 97 49 94 0 1171982720 60958368 10056032
04:05:46 46585 8192000000 10113172832 10569809920 1108466080 11.918 98 25 50 0 1197409296 83175088 5768128
04:07:33 36323 8192000000 10088199088 10540552192 1110775376 11.940 98 30 59 1 1158457360 41593232 6088752
04:09:12 19936 8192000000 10107268608 10532495360 1109911168 11.932 98 47 83 1 1174855424 56482992 8461264
04:10:32 38448 8192000000 10131628288 10590720000 1124163296 12.066 99 49 96 0 1198882208 65239216 9479696
04:12:16 36222 8192000000 10089629024 10534002688 1129586144 12.117 99 26 47 1 1184075712 49654544 4835024
04:13:53 46039 8192000000 10104761264 10552979456 1147683472 12.288 100 36 65 1 1190305296 35797920 6823904
04:16:08 81091 8192000000 10066697824 10557927424 1127030864 12.093 98 25 48 0 1192135408 59917520 5187024
04:18:46 53718 8192000000 10058688000 10531786752 1132666592 12.146 100 17 34 0 1174724192 38711840 3345760
04:21:29 39228 8192000000 10047824592 10526588928 1119011056 12.018 99 8 16 0 1143709632 23053536 1645040
04:23:09 70100 8192000000 10062577040 10543616000 1111495648 11.947 98 8 13 0 1144082416 31385600 1201168
04:25:22 36526 8192000000 10078602288 10540449792 1121977392 12.046 98 21 41 0 1186474816 60296064 4201360
04:27:02 18055 8192000000 10136632352 10580520960 1147955616 12.290 100 45 90 0 1236714544 78710768 10048160
04:28:21 44823 8192000000 10130324592 10592026624 1132825056 12.148 99 35 70 1 1235213632 94530176 7858400
04:30:12 32938 8192000000 10098879680 10626994176 1114805360 11.978 98 35 69 0 1231670976 109561088 7304528
04:31:46 60307 8192000000 10137994128 10590224384 1101999952 11.857 97 46 92 0 1198150416 86753680 9396784
04:33:47 39507 8192000000 10154710224 10636300288 1121995856 12.046 99 40 80 0 1276910288 146786448 8127984
04:35:29 104217 8192000000 10053940496 10550247424 1091302000 11.755 97 14 28 0 1158258752 64348384 2608368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 21-02-2023 04:40:10 to 21-02-2023 04:40:27.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-02-2023 04:40:28 to 21-02-2023 04:40:30.

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          221933
MON$OLDEST_ACTIVE               222418
MON$OLDEST_SNAPSHOT             222418
MON$NEXT_TRANSACTION            222418
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                       2067680
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:77: 
:78: mscs-test-161.budzdorov.ru    Tue Feb 21 04:37:27 2023
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Tue Feb 21 04:37:38 2023
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
--- end of diff output ---

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

21.02.23 04:40:31. 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-02-21 04:40:34 No severe PSQL-related problems occured
21.02.23 04:40:35. End of report.