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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15712084 kB
MemAvailable 31118920 kB
Buffers 20 kB
Cached 15896692 kB
SwapCached 2496 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-30 04:37:18
DTS_END                         2022-06-30 04:37:18.1500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51016
TRN_ID                          217975
ATT_ID                          592
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: 9160.78 22326 1099293
customer order: creation 166.13 519 19936
customer order: refuse 14.53 403 1744
order to supplier: creation 43.82 1416 5258
order to supplier: removal 3.53 161781 424
invoice (draft): creation 43.56 3205 5227
invoice (draft): removal 52.18 976 6261
invoice accept: apply 40.78 26939 4893
invoice accept: cancel 47.68 1187 5722
customer reserve: creation 3808.47 254 457016
customer reserve: removal 4643.80 95 557256
realization accept: apply 81.74 282 9809
realization accept: cancel 89.50 145 10740
payment from customer: creation 55.91 187 6709
payment from customer: removal 5.78 126 693
payment to supplier: creation 47.83 221 5740
payment to supplier: removal 3.40 133 408
service: total inventory turnovers 3.91 4062 469
service: total monetary turnovers 7.33 335 879
service: aggregate perf. data 0.87 16417 104
service: refresh index statistics 0.04 227841 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-06-2022 04:38:29 to 30-06-2022 04:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5429
TEST_TIME 2 5855
TEST_TIME 3 5065
TEST_TIME 4 4908
TEST_TIME 5 5024
TEST_TIME 6 5044
TEST_TIME 7 5526
TEST_TIME 8 5443
TEST_TIME 9 5707
TEST_TIME 10 6063
TEST_TIME 11 6177
TEST_TIME 12 6419
TEST_TIME 13 6728
TEST_TIME 14 6884
TEST_TIME 15 7199
TEST_TIME 16 7180
TEST_TIME 17 7308
TEST_TIME 18 7204
TEST_TIME 19 7336
TEST_TIME 20 7671
TEST_TIME 21 7880
TEST_TIME 22 8063
TEST_TIME 23 8078
TEST_TIME 24 8282
TEST_TIME 25 8440
TEST_TIME 26 8893
TEST_TIME 27 8786
TEST_TIME 28 9026
TEST_TIME 29 9501
TEST_TIME 30 9552
TEST_TIME 31 9619
TEST_TIME 32 10042
TEST_TIME 33 10458
TEST_TIME 34 10980
TEST_TIME 35 11021
TEST_TIME 36 10940
TEST_TIME 37 10918
TEST_TIME 38 10766
TEST_TIME 39 10603
TEST_TIME 40 10482
TEST_TIME 41 10479
TEST_TIME 42 10336
TEST_TIME 43 10370
TEST_TIME 44 10281
TEST_TIME 45 10347
TEST_TIME 46 10192
TEST_TIME 47 10107
TEST_TIME 48 10089
TEST_TIME 49 10056
TEST_TIME 50 10052
TEST_TIME 51 10030
TEST_TIME 52 10027
TEST_TIME 53 9888
TEST_TIME 54 9750
TEST_TIME 55 9716
TEST_TIME 56 9642
TEST_TIME 57 9809
TEST_TIME 58 9872
TEST_TIME 59 9874
TEST_TIME 60 9838
TEST_TIME 61 9869
TEST_TIME 62 9957
TEST_TIME 63 9881
TEST_TIME 64 9845
TEST_TIME 65 9940
TEST_TIME 66 9934
TEST_TIME 67 9975
TEST_TIME 68 9954
TEST_TIME 69 9895
TEST_TIME 70 10011
TEST_TIME 71 9983
TEST_TIME 72 9982
TEST_TIME 73 9868
TEST_TIME 74 9786
TEST_TIME 75 9726
TEST_TIME 76 9676
TEST_TIME 77 9640
TEST_TIME 78 9723
TEST_TIME 79 9707
TEST_TIME 80 9669
TEST_TIME 81 9597
TEST_TIME 82 9586
TEST_TIME 83 9564
TEST_TIME 84 9501
TEST_TIME 85 9455
TEST_TIME 86 9417
TEST_TIME 87 9371
TEST_TIME 88 9316
TEST_TIME 89 9265
TEST_TIME 90 9284
TEST_TIME 91 9307
TEST_TIME 92 9285
TEST_TIME 93 9265
TEST_TIME 94 9232
TEST_TIME 95 9267
TEST_TIME 96 9308
TEST_TIME 97 9298
TEST_TIME 98 9377
TEST_TIME 99 9368
TEST_TIME 100 9383
TEST_TIME 101 9423
TEST_TIME 102 9453
TEST_TIME 103 9442
TEST_TIME 104 9389
TEST_TIME 105 9337
TEST_TIME 106 9302
TEST_TIME 107 9295
TEST_TIME 108 9258
TEST_TIME 109 9244
TEST_TIME 110 9212
TEST_TIME 111 9182
TEST_TIME 112 9171
TEST_TIME 113 9136
TEST_TIME 114 9220
TEST_TIME 115 9205
TEST_TIME 116 9203
TEST_TIME 117 9212
TEST_TIME 118 9230
TEST_TIME 119 9213
TEST_TIME 120 9186

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-06-2022 04:38:33 to 30-06-2022 04:38:36.

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 1521534 1521534 0 0.00 0 20377 14 0 0
doc_list_biud 1521523 1521523 0 0.00 0 75899 41 0 0
sp_add_invoice_to_stock 5081 4893 188 3.70 30 202909 26940 0 188
sp_cancel_adding_invoice 5722 5722 0 0.00 5 90242 1187 0 0
sp_cancel_client_order 1744 1744 0 0.00 5 8576 404 0 0
sp_cancel_customer_reserve 557256 557256 0 0.00 1 20112 96 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 5332 127 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 3959 133 0 0
sp_cancel_supplier_invoice 6270 6261 9 0.14 3 75906 977 0 9
sp_cancel_supplier_order 424 424 0 0.00 9 1558244 161781 0 0
sp_cancel_write_off 10740 10740 0 0.00 2 10247 145 0 0
sp_client_order 19936 19936 0 0.00 5 20140 520 0 0
sp_customer_reserve 457016 457016 0 0.00 0 102754 255 0 0
sp_fill_shopping_cart 30407 30407 0 0.00 1 24469 86 0 0
sp_fill_shopping_cart_clo_res 456990 456990 0 0.00 0 16485 18 0 0
sp_get_clo_for_invoice 4859 4859 0 0.00 0 30520 292 0 0
sp_kill_cost_storno 17563 17563 0 0.00 0 15075 69 0 0
sp_kill_qstorno_ret_qs2qd 1131937 1131937 0 0.00 0 75898 41 0 0
sp_kill_qty_storno 596814 596814 0 0.00 0 75899 95 0 0
sp_lock_selected_doc 20337 20337 0 0.00 0 8200 8 0 0
sp_make_cost_storno 27117 27117 0 0.00 0 15157 84 0 0
sp_multiply_rows_for_pdistr 27116 27116 0 0.00 0 18760 45 0 0
sp_multiply_rows_for_qdistr 19933 19933 0 0.00 0 15201 193 0 0
sp_pay_from_customer 6709 6709 0 0.00 5 10166 187 0 0
sp_pay_to_supplier 5740 5740 0 0.00 7 19730 221 0 0
sp_payment_common 12449 12449 0 0.00 2 7787 52 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 1 8568 329 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 23843 531 0 0
sp_qd_handle_on_reserve_upd_sts 9809 9809 0 0.00 0 11150 56 0 0
sp_reserve_write_off 9809 9809 0 0.00 8 12775 282 0 0
sp_supplier_invoice 5227 5227 0 0.00 11 122122 3205 0 0
sp_supplier_order 5258 5258 0 0.00 7 103228 1416 0 0
srv_aggregate_perf_data 104 104 0 0.00 2211 211425 16418 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 713 118622 34921 0 0
srv_find_qd_qs_mism 1052410 1052410 0 0.00 0 20376 10 0 0
srv_make_invnt_saldo 469 469 0 0.00 63 110755 4063 0 0
srv_make_money_saldo 879 879 0 0.00 1 7965 335 0 0
srv_recalc_idx_stat 5 5 0 0.00 93842 403664 227841 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4897 4897 0 0.00 1 12002 1011 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 2 6887 485 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 1519 25 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 504 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 950 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 7344 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 2768 40 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 101217 6932 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2770 23 0 0
t$perf-norm:sp_client_order 19937 19937 0 0.00 0 4976 36 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 3457 18 0 0
t$perf-norm:sp_pay_from_customer 6709 6709 0 0.00 0 2168 16 0 0
t$perf-norm:sp_pay_to_supplier 5740 5740 0 0.00 0 2276 18 0 0
t$perf-norm:sp_reserve_write_off 9809 9809 0 0.00 0 6571 24 0 0
t$perf-norm:sp_supplier_invoice 5227 5227 0 0.00 0 3079 30 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 2305 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1059 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 149 6 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 1006 16 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 278 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 11 446 145 0 0
v_add_invoice_to_stock 10086 9710 376 3.72 0 1757 25 0 376
v_all_customers 36452 36452 0 0.00 0 60 4 0 0
v_all_suppliers 29730 29730 0 0.00 0 262 5 0 0
v_all_wares 39866 39866 0 0.00 0 166 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2508 19 0 0
v_cancel_client_order 3488 3488 0 0.00 0 3268 11 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 1977 15 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 1 693 11 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 843 13 0 0
v_cancel_supplier_invoice 754 736 18 2.38 0 1290 26 0 18
v_cancel_supplier_order 848 848 0 0.00 0 1775 16 0 0
v_cancel_write_off 1974 1974 0 0.00 0 2700 26 0 0
v_max_id_clo_ord 5253 5253 0 0.00 0 4040 6 0 0
v_max_id_clo_res 943 943 0 0.00 0 943 11 0 0
v_max_id_ord_sup 5221 5221 0 0.00 0 5964 8 0 0
v_max_non_paid_invoice 1348 1348 0 0.00 0 2342 11 0 0
v_max_non_paid_realizn 3407 3407 0 0.00 0 4515 12 0 0
v_min_id_clo_ord 5253 5253 0 0.00 0 5059 10 0 0
v_min_id_clo_res 943 943 0 0.00 0 5046 23 0 0
v_min_id_ord_sup 5221 5221 0 0.00 0 11710 19 0 0
v_min_non_paid_invoice 5740 5740 0 0.00 0 6050 20 0 0
v_min_non_paid_realizn 6708 6708 0 0.00 0 6985 19 0 0
v_our_firm 10016 10016 0 0.00 0 59 5 0 0
v_reserve_write_off 19614 19614 0 0.00 0 3228 11 0 0
x_lock_depdocs_on_canc_invoice 11615 11615 0 0.00 0 18239 128 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 15741 419 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 2 98073 1244 0 0
x_make_qstorno_1200_2000 5227 5227 0 0.00 3 121907 2974 0 0
x_make_qstorno_2100_3300 457016 457016 0 0.00 0 102731 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-06-2022 04:38:37 to 30-06-2022 04:38:41.

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:21 2 8192000000 8798076368 8809140224 4179744 0.050 2 1 2 0 4279136 63616 35776
02:08:22 27294 8192000000 9706154688 10000891904 786795632 8.762 100 91 182 0 817693968 7948528 22949808
02:09:55 14887 8192000000 9758779216 10063695872 831031952 9.210 100 88 174 0 864353168 12556960 20764256
02:11:13 9660 8192000000 9791200656 10105335808 861068512 9.511 100 88 171 0 901701264 20450480 20182272
02:12:26 5719 8192000000 9844634432 10172760064 899284656 9.891 100 86 170 0 955441504 37571632 18585216
02:13:37 3473 8192000000 9899266080 10236366848 937286688 10.266 100 87 163 1 1015063696 60634032 17142976
02:14:43 2961 8192000000 9945194032 10294534144 979433872 10.679 100 87 166 0 1067264032 71673776 16156384
02:15:48 1917 8192000000 9995395536 10351153152 1010448160 10.980 100 92 183 1 1113339792 84073984 18817648
02:16:52 1033 8192000000 9991109328 10356260864 1032590160 11.193 100 96 192 0 1115292352 63617104 19085088
02:17:54 1273 8192000000 10029309440 10405113856 1058733216 11.444 100 93 178 2 1154515616 78649936 17132464
02:18:56 12050 8192000000 10040090864 10415083520 1064008608 11.495 100 88 161 0 1161683312 81417872 16256832
02:20:09 983 8192000000 10028236752 10408763392 1071520912 11.567 100 94 180 2 1150195296 61817072 16857312
02:21:10 1093 8192000000 10030818864 10410881024 1075142176 11.601 100 97 190 2 1152603456 58879552 18581728
02:22:12 954 8192000000 10053322784 10438295552 1081709248 11.664 100 96 187 1 1173721008 72792224 19219536
02:23:14 1095 8192000000 10061112848 10446524416 1084822624 11.693 100 97 188 4 1181731744 77756320 19152800
02:24:16 1176 8192000000 10058434992 10442850304 1094169216 11.782 100 96 178 1 1184887760 70244304 20474240
02:25:18 1117 8192000000 10074575808 10462474240 1095403904 11.794 100 96 186 1 1194478512 79536624 19537984
02:26:20 2859 8192000000 10064613456 10458505216 1098300720 11.822 100 67 129 2 1183484816 73143968 12040128
02:27:24 974 8192000000 10073202640 10460749824 1097329392 11.812 100 89 168 2 1191245872 76689424 17227056
02:28:26 996 8192000000 10062939664 10451640320 1094152064 11.782 100 98 189 3 1185303520 72121232 19030224
02:29:28 1332 8192000000 10044334912 10437312512 1076783504 11.617 99 93 173 0 1160500544 66274688 17442352
02:30:30 1082 8192000000 10020506096 10403491840 1080447008 11.652 100 94 177 4 1149106464 49445344 19214112
02:31:32 10757 8192000000 9990964640 10382401536 1053621744 11.395 99 65 119 1 1100585904 35299504 11664656
02:32:43 7553 8192000000 9980356240 10357710848 1037362640 11.239 100 66 124 3 1095786512 45176272 13247600
02:33:52 4542 8192000000 9999239456 10375131136 1045539728 11.318 100 71 135 2 1121049392 62393536 13116128
02:34:58 1119 8192000000 9994528224 10366607360 1032343616 11.191 100 95 176 1 1113766448 63210688 18212144
02:36:00 979 8192000000 9993346224 10369290240 1039237440 11.257 100 89 158 3 1117782256 61691472 16853344
02:37:02 1474 8192000000 9993646432 10368806912 1040387216 11.268 100 87 172 1 1115361376 56800448 18173712
02:38:13 19616 8192000000 9994855104 10378088448 1052106336 11.381 99 61 107 1 1115145904 51757888 11281680
02:39:41 2053 8192000000 10021977616 10410631168 1073697952 11.587 100 67 121 1 1143064160 55970448 13395760
02:40:57 2262 8192000000 10049702160 10439593984 1088061200 11.724 100 64 124 1 1171188320 70581616 12545504
02:42:05 16382 8192000000 10114497424 10512596992 1105109616 11.886 100 59 113 0 1221103104 103895840 12097648
02:43:28 4239 8192000000 10135710272 10522521600 1116240992 11.991 100 86 147 1 1253282624 122469728 14571904
02:44:40 892 8192000000 10179436976 10570813440 1131367808 12.134 100 92 174 4 1296490272 146743888 18378576
02:45:45 5377 8192000000 10229149424 10630725632 1137322000 12.190 100 71 134 2 1349878416 198152656 14403760
02:46:54 1406 8192000000 10256439616 10665603072 1154304704 12.350 100 90 180 0 1369932640 195720512 19907424
02:47:58 1082 8192000000 10266248624 10666160128 1158291344 12.387 100 98 188 0 1382528112 203215456 21021312
02:49:01 1579 8192000000 10296851344 10707464192 1171439968 12.510 100 92 177 3 1408505376 218984464 18080944
02:50:08 705 8192000000 10338969056 10738352128 1172930784 12.524 100 98 191 1 1448299584 254197424 21171376
02:51:11 888 8192000000 10363152672 10765373440 1173762144 12.532 100 96 189 0 1476094848 280814640 21518064
02:52:14 1182 8192000000 10370525952 10793000960 1169202896 12.489 100 96 186 1 1478914240 287925792 21785552
02:53:20 1366 8192000000 10416750432 10821320704 1176715968 12.560 100 96 186 0 1526353968 327265920 22372080
02:54:26 1176 8192000000 10435725440 10841821184 1174297840 12.537 100 90 176 1 1543541632 349268128 19975664
02:55:30 1042 8192000000 10461055936 10879746048 1180616592 12.596 100 97 185 2 1571468512 367784928 23066992
02:56:35 777 8192000000 10496439392 10924650496 1190552048 12.689 100 99 194 0 1602729152 389679216 22497888
02:57:38 1077 8192000000 10507556288 10933346304 1189632880 12.680 100 97 193 2 1617023664 404661792 22728992
02:58:41 957 8192000000 10507896496 10938654720 1186978688 12.655 100 99 197 2 1612766032 403134736 22652608
02:59:45 791 8192000000 10514417312 10949038080 1176274048 12.555 100 97 193 0 1622038256 422506864 23257344
03:00:51 2015 8192000000 10500683840 10945748992 1160441728 12.407 100 91 174 0 1603464848 421000224 22022896
03:01:56 707 8192000000 10586039904 11009306624 1186257104 12.649 100 100 195 0 1688269968 477876976 24135888
03:02:59 4013 8192000000 10540925856 11029553152 1166741632 12.466 100 61 123 0 1635178640 452750784 15686224
03:04:05 9271 8192000000 10539871568 10978689024 1162620544 12.428 99 51 100 1 1634045536 458470320 12954672
03:05:19 11190 8192000000 10521021168 11019096064 1172215808 12.518 100 42 84 0 1633936384 450219008 11501568
03:06:31 5164 8192000000 10438389008 10932457472 1160977168 12.412 100 36 70 1 1549767648 379722656 9067824
03:09:15 63740 8192000000 10228812912 10787979264 1141616608 12.231 100 23 44 0 1462343696 315120288 5606800
03:11:20 37435 8192000000 10105869840 10631761920 1128312464 12.105 99 12 24 0 1263365712 132136832 2916416
03:13:00 67609 8192000000 10059904896 10506772480 1073059744 11.581 94 6 12 0 1105236064 31151136 1025184
03:15:08 32950 8192000000 10119236720 10554753024 1135874592 12.177 100 23 44 0 1200641520 60035808 4731120
03:16:50 43925 8192000000 10104347568 10530693120 1118639488 12.014 98 37 70 1 1190510688 64193472 7677728
03:18:37 114077 8192000000 10103265680 10567282688 1107046384 11.904 97 41 77 0 1215791552 99741328 9003840
03:21:32 67798 8192000000 10057051648 10547859456 1112542896 11.956 98 26 50 0 1164071328 46372464 5155968
03:24:14 9463 8192000000 10076777360 10499805184 1126703216 12.090 99 88 158 7 1173001968 30125168 16173584
03:25:25 32987 8192000000 10119680720 10561634304 1134656384 12.165 99 31 62 0 1202257984 60802368 6799232
03:27:04 4167 8192000000 10139473904 10580926464 1150400464 12.313 100 76 136 5 1235461776 73356640 11704672
03:28:10 73271 8192000000 10096336160 10559782912 1128291456 12.105 98 12 24 0 1204933376 73894640 2747280
03:30:28 46170 8192000000 10130266544 10598383616 1138522976 12.202 99 13 26 0 1223635840 82359424 2753440
03:32:51 52069 8192000000 10187689712 10681880576 1157370480 12.379 100 27 55 1 1298797728 135128352 6298896
03:35:12 83200 8192000000 10097024448 10581786624 1155440400 12.361 100 13 26 0 1240309840 81793568 3075872
03:37:40 77834 8192000000 10111722032 10578296832 1108545840 11.919 96 20 38 0 1144579184 32516832 3516512
03:39:59 69925 8192000000 10112474272 10560053248 1098712384 11.825 96 15 30 0 1135379648 34231072 2436192
03:42:12 55524 8192000000 10097298592 10607665152 1122434480 12.050 99 28 56 0 1236749216 109158528 5156208
03:44:08 11411 8192000000 10120313744 10550120448 1117170512 12.000 98 56 113 0 1199726752 70651936 11904304
03:45:20 43337 8192000000 10147113968 10603110400 1131626560 12.137 99 29 58 0 1223470448 85840768 6003120
03:47:11 55026 8192000000 10129951744 10585178112 1091215088 11.754 96 20 37 0 1193861328 98843904 3802336
03:49:07 62685 8192000000 10079222752 10571169792 1124834832 12.073 100 39 77 1 1168223728 35155952 8232944
03:51:12 46037 8192000000 10126678000 10567544832 1111222656 11.944 98 35 66 0 1184032512 65918560 6891296
03:53:18 91162 8192000000 10139749280 10632380416 1104171008 11.877 97 18 36 0 1201185136 92833536 4180592
03:55:53 42966 8192000000 10055040880 10542747648 1115933136 11.989 99 13 26 1 1182973648 63945808 3094704
03:57:46 56909 8192000000 10078516976 10513899520 1117322992 12.002 98 38 72 1 1153603808 28100304 8180512
03:59:50 78016 8192000000 10051742960 10557112320 1119346992 12.021 99 10 20 0 1156741936 35175728 2219216
04:02:08 37903 8192000000 10068266080 10511130624 1140996640 12.225 100 26 52 0 1173444752 27352000 5096112
04:04:39 96959 8192000000 10086453216 10543939584 1077610784 11.625 95 16 32 0 1156554016 75528352 3414880
04:07:17 107394 8192000000 10066259680 10626224128 1052607760 11.386 94 2 4 0 1103229360 50393104 228496
04:10:05 20314 8192000000 10082165472 10523066368 1112675296 11.958 99 39 76 0 1172244208 52344032 7224880
04:11:45 23761 8192000000 10140787008 10579013632 1128380080 12.106 100 53 105 0 1240911040 101786704 10744256
04:13:12 14471 8192000000 10089421488 10546933760 1136270640 12.180 100 36 63 0 1178971104 36597504 6102960
04:14:31 22173 8192000000 10143753216 10588938240 1145557456 12.268 100 42 82 0 1236044528 82446608 8040464
04:15:55 35841 8192000000 10115646656 10568130560 1135299552 12.171 99 41 80 0 1192090000 48891776 7898672
04:17:32 43537 8192000000 10110939216 10558119936 1127468976 12.097 98 29 58 0 1209400656 76411216 5520464
04:19:19 56566 8192000000 10102143536 10542084096 1130472480 12.126 99 34 68 0 1178920096 41551760 6895856
04:21:16 90764 8192000000 10098793472 10547798016 1128472352 12.107 99 39 76 1 1186816432 50016064 8328016
04:23:49 15836 8192000000 10086341184 10524581888 1120661872 12.033 99 34 68 0 1174098800 46696736 6740192
04:25:15 118606 8192000000 10091397312 10587250688 1115550736 11.985 98 11 22 0 1196312688 77970384 2791568
04:28:24 96063 8192000000 10121070176 10580312064 1122072576 12.047 98 13 26 0 1215738752 90872976 2793200
04:31:03 28887 8192000000 10130070752 10656243712 1149362992 12.304 100 20 40 0 1235540480 81541024 4636464
04:32:34 56057 8192000000 10100832528 10561282048 1123617856 12.061 98 22 45 0 1206366160 78197456 4550848
04:34:36 28890 8192000000 10121281328 10562535424 1123458624 12.060 98 39 78 0 1233470992 101371472 8640896
04:36:08 33274 8192000000 10144661344 10591420416 1119510320 12.022 98 36 72 1 1212600384 85497024 7593040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-06-2022 04:38:43 to 30-06-2022 04:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 188 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-06-2022 04:39:00 to 30-06-2022 04:39:02.

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          214131
MON$OLDEST_ACTIVE               218084
MON$OLDEST_SNAPSHOT             218084
MON$NEXT_TRANSACTION            218084
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                       2076832
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2793 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2793 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2793 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2793 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Jun 30 04:37:24 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Jun 30 04:37:24 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Jun 30 04:37:34 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Jun 30 04:37:35 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

30.06.22 04:39:03. 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-30 04:39:06 No severe PSQL-related problems occured
30.06.22 04:39:07. End of report.