Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2022 07: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 15046444 kB
MemAvailable 30562172 kB
Buffers 0 kB
Cached 16686192 kB
SwapCached 2128 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 2022-10-10 07:37:21
DTS_END                         2022-10-10 07:37:21.0870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44932
TRN_ID                          224445
ATT_ID                          650
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: 9049.55 27626 1085946
customer order: creation 169.64 448 20357
customer order: refuse 14.54 414 1745
order to supplier: creation 46.41 1401 5569
order to supplier: removal 3.53 152196 423
invoice (draft): creation 45.42 3188 5450
invoice (draft): removal 49.26 917 5911
invoice accept: apply 42.52 24272 5102
invoice accept: cancel 45.19 1205 5423
customer reserve: creation 3896.46 231 467575
customer reserve: removal 4442.08 95 533050
realization accept: apply 81.93 297 9832
realization accept: cancel 81.47 163 9777
payment from customer: creation 60.13 196 7216
payment from customer: removal 5.63 135 675
payment to supplier: creation 49.44 205 5933
payment to supplier: removal 3.39 108 407
service: total inventory turnovers 3.88 4040 466
service: total monetary turnovers 7.77 387 932
service: aggregate perf. data 0.83 16529 99
service: refresh index statistics 0.03 346084 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2022 07:39:10 to 10-10-2022 07:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7399
TEST_TIME 2 5805
TEST_TIME 3 5630
TEST_TIME 4 6845
TEST_TIME 5 7055
TEST_TIME 6 7050
TEST_TIME 7 7689
TEST_TIME 8 7859
TEST_TIME 9 7872
TEST_TIME 10 8034
TEST_TIME 11 7921
TEST_TIME 12 7835
TEST_TIME 13 7748
TEST_TIME 14 7986
TEST_TIME 15 8050
TEST_TIME 16 8014
TEST_TIME 17 8292
TEST_TIME 18 8616
TEST_TIME 19 8881
TEST_TIME 20 8770
TEST_TIME 21 8672
TEST_TIME 22 8735
TEST_TIME 23 8665
TEST_TIME 24 8582
TEST_TIME 25 8834
TEST_TIME 26 8884
TEST_TIME 27 9097
TEST_TIME 28 9148
TEST_TIME 29 9316
TEST_TIME 30 9342
TEST_TIME 31 9735
TEST_TIME 32 9520
TEST_TIME 33 10143
TEST_TIME 34 10492
TEST_TIME 35 10930
TEST_TIME 36 11231
TEST_TIME 37 11172
TEST_TIME 38 11043
TEST_TIME 39 10971
TEST_TIME 40 10852
TEST_TIME 41 10771
TEST_TIME 42 10609
TEST_TIME 43 10435
TEST_TIME 44 10390
TEST_TIME 45 10279
TEST_TIME 46 10421
TEST_TIME 47 10421
TEST_TIME 48 10404
TEST_TIME 49 10340
TEST_TIME 50 10218
TEST_TIME 51 10191
TEST_TIME 52 10138
TEST_TIME 53 10094
TEST_TIME 54 10123
TEST_TIME 55 10072
TEST_TIME 56 10171
TEST_TIME 57 10014
TEST_TIME 58 9860
TEST_TIME 59 9781
TEST_TIME 60 9658
TEST_TIME 61 9645
TEST_TIME 62 9597
TEST_TIME 63 9548
TEST_TIME 64 9578
TEST_TIME 65 9566
TEST_TIME 66 9500
TEST_TIME 67 9440
TEST_TIME 68 9382
TEST_TIME 69 9282
TEST_TIME 70 9299
TEST_TIME 71 9439
TEST_TIME 72 9483
TEST_TIME 73 9454
TEST_TIME 74 9524
TEST_TIME 75 9496
TEST_TIME 76 9496
TEST_TIME 77 9423
TEST_TIME 78 9472
TEST_TIME 79 9581
TEST_TIME 80 9552
TEST_TIME 81 9597
TEST_TIME 82 9654
TEST_TIME 83 9630
TEST_TIME 84 9591
TEST_TIME 85 9499
TEST_TIME 86 9444
TEST_TIME 87 9377
TEST_TIME 88 9369
TEST_TIME 89 9330
TEST_TIME 90 9317
TEST_TIME 91 9309
TEST_TIME 92 9271
TEST_TIME 93 9232
TEST_TIME 94 9223
TEST_TIME 95 9216
TEST_TIME 96 9244
TEST_TIME 97 9253
TEST_TIME 98 9241
TEST_TIME 99 9224
TEST_TIME 100 9277
TEST_TIME 101 9260
TEST_TIME 102 9282
TEST_TIME 103 9276
TEST_TIME 104 9266
TEST_TIME 105 9332
TEST_TIME 106 9318
TEST_TIME 107 9331
TEST_TIME 108 9424
TEST_TIME 109 9352
TEST_TIME 110 9312
TEST_TIME 111 9287
TEST_TIME 112 9270
TEST_TIME 113 9260
TEST_TIME 114 9240
TEST_TIME 115 9226
TEST_TIME 116 9187
TEST_TIME 117 9144
TEST_TIME 118 9094
TEST_TIME 119 9137
TEST_TIME 120 9079

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2022 07:39:14 to 10-10-2022 07:39:17.

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 1517339 1517339 0 0.00 0 28703 13 0 0
doc_list_biud 1517328 1517328 0 0.00 0 30417 39 0 0
sp_add_invoice_to_stock 5247 5102 145 2.76 26 192072 24272 0 145
sp_cancel_adding_invoice 5423 5423 0 0.00 6 77927 1205 0 0
sp_cancel_client_order 1745 1745 0 0.00 6 9870 414 0 0
sp_cancel_customer_reserve 533050 533050 0 0.00 1 30459 95 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 4821 135 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 4610 109 0 0
sp_cancel_supplier_invoice 5923 5911 12 0.20 2 27239 917 0 12
sp_cancel_supplier_order 423 423 0 0.00 21 1459258 152197 0 0
sp_cancel_write_off 9777 9777 0 0.00 2 10950 163 0 0
sp_client_order 20357 20357 0 0.00 5 36255 449 0 0
sp_customer_reserve 467575 467575 0 0.00 0 51011 231 0 0
sp_fill_shopping_cart 31362 31362 0 0.00 1 22176 89 0 0
sp_fill_shopping_cart_clo_res 467547 467547 0 0.00 0 25622 18 0 0
sp_get_clo_for_invoice 5066 5066 0 0.00 0 27885 274 0 0
sp_kill_cost_storno 16282 16282 0 0.00 0 5743 71 0 0
sp_kill_qstorno_ret_qs2qd 1082211 1082211 0 0.00 0 30332 41 0 0
sp_kill_qty_storno 571225 571225 0 0.00 0 30412 95 0 0
sp_lock_selected_doc 20516 20516 0 0.00 0 7574 7 0 0
sp_make_cost_storno 28047 28047 0 0.00 0 9316 84 0 0
sp_multiply_rows_for_pdistr 28045 28045 0 0.00 0 7397 40 0 0
sp_multiply_rows_for_qdistr 20354 20354 0 0.00 0 10721 150 0 0
sp_pay_from_customer 7216 7216 0 0.00 6 11294 196 0 0
sp_pay_to_supplier 5933 5933 0 0.00 6 11297 206 0 0
sp_payment_common 13147 13147 0 0.00 2 10782 54 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 9854 342 0 0
sp_qd_handle_on_invoice_upd_sts 10489 10489 0 0.00 0 13257 523 0 0
sp_qd_handle_on_reserve_upd_sts 9830 9830 0 0.00 0 12822 69 0 0
sp_reserve_write_off 9832 9832 0 0.00 8 14633 297 0 0
sp_supplier_invoice 5450 5450 0 0.00 13 84993 3189 0 0
sp_supplier_order 5569 5569 0 0.00 8 29486 1401 0 0
srv_aggregate_perf_data 99 99 0 0.00 2418 173306 16530 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 743 141021 35797 0 0
srv_find_qd_qs_mism 1037180 1037180 0 0.00 0 28684 9 0 0
srv_make_invnt_saldo 466 466 0 0.00 51 75085 4040 0 0
srv_make_money_saldo 932 932 0 0.00 1 22474 387 0 0
srv_recalc_idx_stat 4 4 0 0.00 194475 430622 346084 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5110 5110 0 0.00 0 10466 970 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 9121 525 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 1086 27 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 2315 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2310 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 628 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 4093 49 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 84157 6842 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 4065 28 0 0
t$perf-norm:sp_client_order 20358 20358 0 0.00 0 7108 36 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 6928 31 0 0
t$perf-norm:sp_pay_from_customer 7216 7216 0 0.00 0 8212 22 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 6609 21 0 0
t$perf-norm:sp_reserve_write_off 9833 9833 0 0.00 0 7277 26 0 0
t$perf-norm:sp_supplier_invoice 5450 5450 0 0.00 0 6514 34 0 0
t$perf-norm:sp_supplier_order 5569 5569 0 0.00 0 5034 32 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 282 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 52 5 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 609 14 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 732 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 196 56 0 0
v_add_invoice_to_stock 10412 10122 290 2.78 0 5263 27 0 290
v_all_customers 37662 37662 0 0.00 0 85 4 0 0
v_all_suppliers 30528 30528 0 0.00 0 66 5 0 0
v_all_wares 40704 40704 0 0.00 0 76 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 262 13 0 0
v_cancel_client_order 3490 3490 0 0.00 0 1245 10 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 2553 16 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 2043 16 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 412 11 0 0
v_cancel_supplier_invoice 702 678 24 3.41 0 837 25 0 24
v_cancel_supplier_order 846 846 0 0.00 0 3224 18 0 0
v_cancel_write_off 1940 1940 0 0.00 0 1685 25 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 9761 7 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2217 10 0 0
v_max_id_ord_sup 5443 5443 0 0.00 0 8721 9 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 61 8 0 0
v_max_non_paid_realizn 3627 3627 0 0.00 0 914 8 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 9765 13 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 2114 9 0 0
v_min_id_ord_sup 5443 5443 0 0.00 0 5417 11 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 10770 21 0 0
v_min_non_paid_realizn 7216 7216 0 0.00 0 8636 27 0 0
v_our_firm 10220 10220 0 0.00 0 62 5 0 0
v_reserve_write_off 19656 19656 0 0.00 0 6223 13 0 0
x_lock_depdocs_on_canc_invoice 10995 10995 0 0.00 0 13289 129 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 7880 413 0 0
x_make_qstorno_1000_1200 5569 5569 0 0.00 2 29446 1211 0 0
x_make_qstorno_1200_2000 5450 5450 0 0.00 5 80664 2954 0 0
x_make_qstorno_2100_3300 467574 467574 0 0.00 0 51009 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2022 07:39:18 to 10-10-2022 07:39:22.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:24 4 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 33680 8192000000 9729671728 10032324608 803197408 8.929 100 89 178 0 833296064 8408640 21690016
05:10:05 22459 8192000000 9768505312 10078302208 840089792 9.301 100 92 184 0 876529072 14659264 21780016
05:11:30 8012 8192000000 9800958640 10121064448 866423808 9.564 100 85 168 0 909259328 23310544 19524976
05:12:53 6866 8192000000 9863046384 10197028864 910982560 10.007 100 81 161 0 974724592 46109008 17633024
05:14:05 2706 8192000000 9937354096 10277208064 958613136 10.475 100 95 185 0 1047472624 69875600 18983888
05:15:11 1842 8192000000 9973286144 10328326144 997345136 10.853 100 92 179 0 1085899088 70399904 18154048
05:16:14 1283 8192000000 10009162000 10372476928 1023919984 11.110 100 97 193 0 1125703856 82231648 19552224
05:17:17 1076 8192000000 10001626608 10378645504 1048505968 11.346 100 97 187 0 1130229168 62690272 19032928
05:18:19 803 8192000000 10034477600 10408898560 1062468112 11.480 100 95 186 2 1154843152 73350032 19025008
05:19:21 10738 8192000000 10033383248 10408542208 1067413216 11.527 100 94 177 0 1150192560 66276000 16503344
05:20:33 1069 8192000000 10027459200 10404335616 1073717648 11.588 100 82 155 0 1149661312 61120176 14823488
05:21:35 3097 8192000000 10055407664 10435313664 1082800736 11.674 100 88 165 0 1172130608 72934912 16394960
05:22:40 1103 8192000000 10061957392 10449080320 1090169152 11.744 100 91 174 1 1183738928 75736464 17833312
05:23:42 1038 8192000000 10055901744 10447818752 1095852800 11.798 100 94 183 0 1184665008 69917264 18894944
05:24:44 1006 8192000000 10061085968 10451730432 1097445872 11.813 100 84 165 0 1182469056 68756512 16266672
05:25:46 1088 8192000000 10075625936 10467176448 1099724224 11.835 100 84 159 2 1199917296 84404800 15788272
05:26:48 1064 8192000000 10065078608 10458308608 1101928688 11.856 100 94 184 0 1187470112 66156672 19384752
05:27:50 6726 8192000000 10069305136 10467213312 1090438992 11.747 99 67 127 0 1185103904 81726192 12938720
05:28:58 993 8192000000 10048935360 10436321280 1083187200 11.678 99 83 157 0 1173498224 73890352 16420672
05:30:00 1092 8192000000 10052078128 10435055616 1071618720 11.568 100 92 174 0 1173460544 83206240 18635584
05:31:01 2094 8192000000 10026891680 10414931968 1065859648 11.513 99 79 153 1 1150135856 67932784 16343424
05:32:05 5754 8192000000 9991326336 10380582912 1050473104 11.365 99 66 126 1 1109213456 45813120 12927232
05:33:13 1223 8192000000 9986794816 10357288960 1030992480 11.178 99 97 183 1 1110660464 60558224 19109760
05:34:15 1052 8192000000 10022173152 10397728768 1047657632 11.338 100 91 166 5 1144437584 78295120 18484832
05:35:17 2915 8192000000 9992546368 10374471680 1050519872 11.366 100 64 120 0 1113893296 51413488 11959936
05:36:21 905 8192000000 9985620016 10359300096 1037659968 11.242 100 95 172 2 1109250176 54131088 17459120
05:37:23 5881 8192000000 9967482992 10347663360 1041670720 11.281 100 56 104 1 1084888464 32018736 11199008
05:38:41 21446 8192000000 10031579232 10420359168 1063170528 11.487 100 65 117 1 1139298752 65210688 10917536
05:40:07 9650 8192000000 10041429776 10433507328 1086967888 11.714 100 55 107 1 1156878272 58304688 11605696
05:41:19 836 8192000000 10103015552 10485788672 1089008560 11.733 100 96 184 0 1221284656 113795104 18480992
05:42:24 2014 8192000000 10093715024 10484858880 1097526768 11.814 100 77 142 1 1213903568 101839312 14537488
05:43:30 2021 8192000000 10104395376 10500882432 1113194960 11.963 100 88 165 1 1219728144 89927600 16605584
05:44:38 2581 8192000000 10134097104 10533400576 1124644080 12.071 100 81 157 2 1247730592 107178368 15908144
05:45:44 1176 8192000000 10160831136 10562641920 1140416592 12.219 100 98 191 4 1276372304 115441024 20514688
05:46:47 1128 8192000000 10182051264 10575106048 1150809664 12.317 100 93 182 1 1298353808 128700048 18844096
05:47:54 1128 8192000000 10192768176 10592104448 1160732624 12.410 100 87 168 1 1303903264 124877184 18293456
05:49:02 1595 8192000000 10215987456 10621132800 1158965488 12.394 100 90 172 1 1323981904 145013680 20002736
05:50:09 1300 8192000000 10222427792 10620440576 1159040016 12.394 100 90 175 1 1336179552 159137888 18001648
05:51:12 867 8192000000 10299180560 10712862720 1181090800 12.600 100 99 192 1 1410451216 207692592 21667824
05:52:16 1236 8192000000 10313596000 10726064128 1185759744 12.644 100 92 178 2 1422107552 215921904 20425904
05:53:20 1948 8192000000 10318152928 10731642880 1180823392 12.598 100 92 176 4 1427606592 226325328 20457872
05:54:25 879 8192000000 10310606272 10754953216 1184813920 12.635 100 98 187 2 1419699856 212517648 22368288
05:55:27 921 8192000000 10344276560 10789138432 1197566368 12.754 100 88 165 1 1455370368 239170064 18633936
05:56:30 1719 8192000000 10339909904 10762448896 1198816816 12.765 100 89 176 1 1448313936 229043024 20454096
05:57:35 4039 8192000000 10351032400 10765430784 1184497888 12.632 100 61 120 0 1465559232 266488928 14572416
05:58:44 1371 8192000000 10406683232 10819088384 1200740128 12.783 100 93 175 1 1507265248 287090928 19434192
05:59:48 794 8192000000 10454436624 10866372608 1197993552 12.758 100 89 178 1 1560352464 340791440 21567472
06:00:51 1406 8192000000 10483853888 10903842816 1200331200 12.779 100 75 144 2 1591127952 373259664 17537088
06:01:56 733 8192000000 10500874176 10950905856 1191395776 12.696 100 93 182 0 1603861680 389801424 22664480
06:03:00 1144 8192000000 10500919296 10934009856 1196095328 12.740 100 87 168 2 1609888896 392740624 21052944
06:04:08 2429 8192000000 10482921312 10944442368 1180111520 12.591 100 70 140 0 1589808416 392233696 17463200
06:05:21 6375 8192000000 10525406880 10973908992 1188301088 12.668 100 58 115 0 1621469376 418766064 14402224
06:06:36 8101 8192000000 10524441760 11005751296 1189202560 12.676 100 38 75 1 1625776896 426779456 9794880
06:08:08 28692 8192000000 10486361760 11007533056 1176966416 12.562 100 38 75 0 1616698608 430008704 9723488
06:09:38 15099 8192000000 10453404016 10950942720 1143421264 12.248 99 42 84 0 1575241216 420864960 10954992
06:11:00 26318 8192000000 10348347520 10889834496 1138372784 12.200 100 24 46 0 1487181424 342879920 5928720
06:12:29 38935 8192000000 10098367504 10621784064 1094867536 11.789 97 13 26 0 1290407584 192442272 3097776
06:14:08 25061 8192000000 10075117552 10533994496 1090142416 11.744 98 24 44 0 1153982768 58743168 5097184
06:15:56 68087 8192000000 10057805664 10522476544 1073435552 11.585 96 11 20 0 1113891904 38427328 2029024
06:18:10 77919 8192000000 10034944320 10481430528 1074798576 11.598 96 19 35 0 1097624128 20223520 2602032
06:20:28 25595 8192000000 10083190448 10516185088 1113528304 11.966 99 34 69 0 1171061280 50009872 7523104
06:21:54 61738 8192000000 10144859216 10605342720 1134863760 12.167 100 13 28 0 1235754672 97905824 2985088
06:23:57 141020 8192000000 10062146496 10514841600 1082558464 11.672 95 13 23 1 1122725040 37828288 2338288
06:27:25 59522 8192000000 10095393648 10537082880 1140705072 12.222 100 36 71 3 1184905184 36232128 7967984
06:29:32 54494 8192000000 10091687824 10517737472 1106668752 11.901 97 29 54 0 1145021520 32391328 5961440
06:31:33 134091 8192000000 10098165920 10594009088 1089128784 11.734 95 9 18 0 1142251744 51177888 1945072
06:34:47 104406 8192000000 10088007072 10557001728 1128015648 12.103 99 27 49 1 1167066752 33825200 5225904
06:37:32 30544 8192000000 10081738064 10547564544 1121928416 12.045 99 19 38 0 1177667248 51879680 3859152
06:39:05 21095 8192000000 10090210112 10524909568 1141014720 12.225 100 45 88 0 1191426736 41304880 9107136
06:40:31 33712 8192000000 10152715968 10594287616 1146020656 12.272 100 37 74 0 1226646224 72217728 8407840
06:42:16 25426 8192000000 10101875984 10574979072 1150091264 12.310 100 21 42 1 1202587024 47914864 4580896
06:43:51 34090 8192000000 10138934096 10600845312 1165617056 12.456 100 12 24 0 1231094784 62530512 2947216
06:45:26 72042 8192000000 10201200208 10657755136 1155602512 12.362 99 20 38 0 1287106224 126616576 4887136
06:47:38 81223 8192000000 10125464736 10644676608 1128952528 12.111 97 14 28 1 1252134016 120009488 3172000
06:50:00 53047 8192000000 10096267040 10533662720 1088656240 11.730 94 15 30 0 1138080736 46396624 3027872
06:52:14 20225 8192000000 10075972976 10504359936 1132416048 12.144 100 52 101 1 1172357552 29641376 10300128
06:53:37 31122 8192000000 10123932144 10561417216 1136551520 12.183 99 38 73 0 1212512704 68600704 7360480
06:55:09 33208 8192000000 10157923568 10618368000 1090172800 11.744 95 38 74 0 1209363664 111524000 7666864
06:56:47 74917 8192000000 10127627504 10577223680 1114398960 11.974 98 19 38 0 1173821920 55448128 3974832
06:59:02 41503 8192000000 10097126224 10551369728 1109640736 11.929 99 20 40 0 1164280688 50615616 4024336
07:00:49 30379 8192000000 10081876336 10517708800 1095651872 11.796 98 34 67 0 1135312880 33043856 6617152
07:02:21 100627 8192000000 10040960672 10475773952 1058959728 11.447 95 28 56 0 1090293248 26079328 5254192
07:05:57 65807 8192000000 10107067744 10571571200 1103530704 11.871 98 16 32 0 1217118336 109565072 4022560
07:09:03 67053 8192000000 10079532400 10561449984 1121902768 12.045 99 17 34 0 1198988080 73193616 3891696
07:11:10 71074 8192000000 10106317392 10592223232 1114537184 11.975 99 12 24 0 1197110608 79864976 2708448
07:13:22 62398 8192000000 10074681632 10523926528 1108192368 11.915 98 15 28 0 1160740160 49757024 2790768
07:15:35 20773 8192000000 10067406208 10506768384 1114264416 11.973 100 40 78 0 1171011904 48873952 7873536
07:16:58 57262 8192000000 10080932144 10524188672 1096281600 11.802 97 14 28 0 1140417808 41416528 2719680
07:19:03 37521 8192000000 10067101488 10500960256 1096766320 11.807 97 38 76 0 1147153504 42683664 7703520
07:20:42 25845 8192000000 10139679504 10582044672 1137382768 12.191 100 36 72 0 1240530512 96049344 7098400
07:22:09 52622 8192000000 10124309168 10566594560 1116263968 11.992 98 35 68 0 1204616784 81539872 6812944
07:24:50 56920 8192000000 10079287680 10597478400 1135327920 12.172 99 22 44 0 1226402288 86761008 4313360
07:26:57 126619 8192000000 10095521792 10567409664 1124603504 12.070 98 29 58 0 1217601760 86935648 6062608
07:30:05 50906 8192000000 10066483792 10573221888 1132877984 12.148 100 16 33 0 1177702144 41158560 3665600
07:31:58 84206 8192000000 10051708624 10527760384 1083210000 11.678 95 23 45 1 1131042064 43071008 4761056
07:34:22 97090 8192000000 10081361728 10539347968 1108727008 11.920 98 13 26 1 1180674800 68874960 3072832
07:37:00 36373 8192000000 9013296560 9277308928 173699728 2.076 16 1 2 0 184682816 10947680 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 10-10-2022 07:39:24 to 10-10-2022 07:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2022 07:39:42 to 10-10-2022 07:39:44.

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          224720
MON$OLDEST_ACTIVE               224721
MON$OLDEST_SNAPSHOT             224721
MON$NEXT_TRANSACTION            224721
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                       2089312
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Mon Oct 10 07:37:41 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

10.10.22 07:39:45. 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-10-10 07:39:48 No severe PSQL-related problems occured
10.10.22 07:39:49. End of report.