Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.04.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 16425460 kB
MemAvailable 31478628 kB
Buffers 20 kB
Cached 15198916 kB
SwapCached 2364 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-04-10 04:37:16
DTS_END                         2022-04-10 04:37:16.0860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55640
TRN_ID                          214171
ATT_ID                          634
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: 8883.61 30172 1066033
customer order: creation 160.20 507 19224
customer order: refuse 14.16 388 1699
order to supplier: creation 43.33 1364 5200
order to supplier: removal 3.46 167296 415
invoice (draft): creation 44.90 3514 5388
invoice (draft): removal 51.63 963 6196
invoice accept: apply 39.22 27133 4706
invoice accept: cancel 46.31 1172 5557
customer reserve: creation 3682.46 257 441895
customer reserve: removal 4509.73 102 541167
realization accept: apply 79.10 256 9492
realization accept: cancel 86.41 178 10369
payment from customer: creation 56.32 173 6758
payment from customer: removal 5.56 125 667
payment to supplier: creation 45.40 183 5448
payment to supplier: removal 3.27 131 392
service: total inventory turnovers 3.83 4578 459
service: total monetary turnovers 7.33 385 880
service: aggregate perf. data 0.97 16925 116
service: refresh index statistics 0.04 377800 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-04-2022 04:38:37 to 10-04-2022 04:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7697
TEST_TIME 2 6934
TEST_TIME 3 6542
TEST_TIME 4 6707
TEST_TIME 5 6941
TEST_TIME 6 6860
TEST_TIME 7 7101
TEST_TIME 8 6917
TEST_TIME 9 6874
TEST_TIME 10 6720
TEST_TIME 11 6677
TEST_TIME 12 6678
TEST_TIME 13 6718
TEST_TIME 14 6746
TEST_TIME 15 6810
TEST_TIME 16 6855
TEST_TIME 17 7160
TEST_TIME 18 7436
TEST_TIME 19 7670
TEST_TIME 20 7752
TEST_TIME 21 8116
TEST_TIME 22 8103
TEST_TIME 23 8201
TEST_TIME 24 8143
TEST_TIME 25 8120
TEST_TIME 26 8400
TEST_TIME 27 9146
TEST_TIME 28 9461
TEST_TIME 29 9744
TEST_TIME 30 9833
TEST_TIME 31 10033
TEST_TIME 32 11043
TEST_TIME 33 11122
TEST_TIME 34 10870
TEST_TIME 35 10680
TEST_TIME 36 10465
TEST_TIME 37 10288
TEST_TIME 38 10217
TEST_TIME 39 10351
TEST_TIME 40 10287
TEST_TIME 41 10215
TEST_TIME 42 10160
TEST_TIME 43 10087
TEST_TIME 44 10104
TEST_TIME 45 9952
TEST_TIME 46 9978
TEST_TIME 47 9790
TEST_TIME 48 9688
TEST_TIME 49 9609
TEST_TIME 50 9566
TEST_TIME 51 9590
TEST_TIME 52 9600
TEST_TIME 53 9563
TEST_TIME 54 9517
TEST_TIME 55 9520
TEST_TIME 56 9492
TEST_TIME 57 9514
TEST_TIME 58 9537
TEST_TIME 59 9494
TEST_TIME 60 9655
TEST_TIME 61 9855
TEST_TIME 62 9815
TEST_TIME 63 9778
TEST_TIME 64 9730
TEST_TIME 65 9621
TEST_TIME 66 9552
TEST_TIME 67 9549
TEST_TIME 68 9464
TEST_TIME 69 9391
TEST_TIME 70 9325
TEST_TIME 71 9256
TEST_TIME 72 9161
TEST_TIME 73 9147
TEST_TIME 74 9110
TEST_TIME 75 9147
TEST_TIME 76 9157
TEST_TIME 77 9135
TEST_TIME 78 9139
TEST_TIME 79 9182
TEST_TIME 80 9211
TEST_TIME 81 9237
TEST_TIME 82 9315
TEST_TIME 83 9339
TEST_TIME 84 9276
TEST_TIME 85 9294
TEST_TIME 86 9347
TEST_TIME 87 9328
TEST_TIME 88 9258
TEST_TIME 89 9197
TEST_TIME 90 9180
TEST_TIME 91 9121
TEST_TIME 92 9062
TEST_TIME 93 9026
TEST_TIME 94 8992
TEST_TIME 95 8993
TEST_TIME 96 9029
TEST_TIME 97 9073
TEST_TIME 98 9053
TEST_TIME 99 9040
TEST_TIME 100 9029
TEST_TIME 101 9022
TEST_TIME 102 9003
TEST_TIME 103 9043
TEST_TIME 104 9161
TEST_TIME 105 9148
TEST_TIME 106 9282
TEST_TIME 107 9223
TEST_TIME 108 9152
TEST_TIME 109 9138
TEST_TIME 110 9131
TEST_TIME 111 9074
TEST_TIME 112 9051
TEST_TIME 113 8994
TEST_TIME 114 8978
TEST_TIME 115 8956
TEST_TIME 116 8998
TEST_TIME 117 8977
TEST_TIME 118 8939
TEST_TIME 119 8947
TEST_TIME 120 8915

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-04-2022 04:38:41 to 10-04-2022 04:38:44.

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 1472624 1472624 0 0.00 0 15459 13 0 0
doc_list_biud 1472606 1472606 0 0.00 0 29800 43 0 0
sp_add_invoice_to_stock 4782 4706 76 1.58 69 246304 27134 0 76
sp_cancel_adding_invoice 5557 5557 0 0.00 7 86382 1173 0 0
sp_cancel_client_order 1699 1699 0 0.00 6 11767 388 0 0
sp_cancel_customer_reserve 541167 541167 0 0.00 1 25353 103 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 6677 126 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 5339 131 0 0
sp_cancel_supplier_invoice 6199 6196 3 0.04 4 23944 963 0 3
sp_cancel_supplier_order 415 415 0 0.00 18 1604150 167296 0 0
sp_cancel_write_off 10369 10369 0 0.00 2 7516 178 0 0
sp_client_order 19224 19224 0 0.00 6 70740 507 0 0
sp_customer_reserve 441895 441895 0 0.00 1 43920 258 0 0
sp_fill_shopping_cart 29797 29797 0 0.00 1 13480 82 0 0
sp_fill_shopping_cart_clo_res 441861 441861 0 0.00 0 20631 20 0 0
sp_get_clo_for_invoice 4659 4659 0 0.00 0 30737 295 0 0
sp_kill_cost_storno 16985 16985 0 0.00 0 14187 67 0 0
sp_kill_qstorno_ret_qs2qd 1099314 1099314 0 0.00 0 23932 46 0 0
sp_kill_qty_storno 579550 579550 0 0.00 0 29799 103 0 0
sp_lock_selected_doc 19710 19710 0 0.00 0 5313 8 0 0
sp_make_cost_storno 26357 26357 0 0.00 0 14866 76 0 0
sp_multiply_rows_for_pdistr 26355 26355 0 0.00 0 13217 38 0 0
sp_multiply_rows_for_qdistr 19223 19223 0 0.00 0 70663 218 0 0
sp_pay_from_customer 6758 6758 0 0.00 5 21624 174 0 0
sp_pay_to_supplier 5448 5448 0 0.00 6 19411 184 0 0
sp_payment_common 12206 12206 0 0.00 2 6679 46 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 11744 319 0 0
sp_qd_handle_on_invoice_upd_sts 10213 10213 0 0.00 0 29798 488 0 0
sp_qd_handle_on_reserve_upd_sts 9491 9491 0 0.00 0 12925 60 0 0
sp_reserve_write_off 9492 9492 0 0.00 9 14242 256 0 0
sp_supplier_invoice 5388 5388 0 0.00 11 109863 3515 0 0
sp_supplier_order 5200 5200 0 0.00 7 48202 1364 0 0
srv_aggregate_perf_data 116 116 0 0.00 3018 154255 16926 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 892 140591 37351 0 0
srv_find_qd_qs_mism 1019469 1019469 0 0.00 0 15458 10 0 0
srv_make_invnt_saldo 459 459 0 0.00 81 90148 4579 0 0
srv_make_money_saldo 880 880 0 0.00 1 13602 386 0 0
srv_recalc_idx_stat 5 5 0 0.00 281828 459437 377800 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4712 4712 0 0.00 1 14305 902 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 8813 437 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 14389 35 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 3244 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 464 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1426 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1663 32 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 73512 5710 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 15472 44 0 0
t$perf-norm:sp_client_order 19225 19225 0 0.00 0 13564 34 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 17117 45 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 6702 19 0 0
t$perf-norm:sp_pay_to_supplier 5448 5448 0 0.00 0 6849 18 0 0
t$perf-norm:sp_reserve_write_off 9492 9492 0 0.00 0 7732 24 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 17032 35 0 0
t$perf-norm:sp_supplier_order 5200 5200 0 0.00 0 7708 32 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 782 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 299 9 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 3231 28 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 12045 19 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 198 88 0 0
v_add_invoice_to_stock 9460 9308 152 1.60 0 4313 23 0 152
v_all_customers 35496 35496 0 0.00 0 97 3 0 0
v_all_suppliers 29217 29217 0 0.00 0 134 4 0 0
v_all_wares 38434 38434 0 0.00 0 105 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1639 16 0 0
v_cancel_client_order 3398 3398 0 0.00 0 1277 8 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1773 13 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 316 9 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 686 10 0 0
v_cancel_supplier_invoice 784 778 6 0.76 0 1612 25 0 6
v_cancel_supplier_order 830 830 0 0.00 0 2549 13 0 0
v_cancel_write_off 1930 1930 0 0.00 0 631 21 0 0
v_max_id_clo_ord 5199 5199 0 0.00 0 353 5 0 0
v_max_id_clo_res 965 965 0 0.00 0 3019 14 0 0
v_max_id_ord_sup 5380 5380 0 0.00 0 5642 7 0 0
v_max_non_paid_invoice 1417 1417 0 0.00 0 770 8 0 0
v_max_non_paid_realizn 3396 3396 0 0.00 0 3529 8 0 0
v_min_id_clo_ord 5199 5199 0 0.00 0 1756 8 0 0
v_min_id_clo_res 965 965 0 0.00 0 2375 12 0 0
v_min_id_ord_sup 5380 5380 0 0.00 0 3692 8 0 0
v_min_non_paid_invoice 5446 5446 0 0.00 0 6004 17 0 0
v_min_non_paid_realizn 6758 6758 0 0.00 0 6669 21 0 0
v_our_firm 9662 9662 0 0.00 0 98 4 0 0
v_reserve_write_off 18980 18980 0 0.00 0 6411 10 0 0
x_lock_depdocs_on_canc_invoice 11364 11364 0 0.00 0 10881 119 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 19040 408 0 0
x_make_qstorno_1000_1200 5200 5200 0 0.00 2 48162 1193 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 5 109698 3298 0 0
x_make_qstorno_2100_3300 441894 441894 0 0.00 0 38773 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-04-2022 04:38:45 to 10-04-2022 04:38:49.

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:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 29029 8192000000 9729230864 10026713088 803699808 8.934 100 92 184 0 834866336 7903216 23263312
02:09:56 28463 8192000000 9765441120 10073468928 835744432 9.257 100 91 182 0 871179376 13663264 21771680
02:11:28 8834 8192000000 9803550992 10125549568 873245040 9.632 100 85 170 0 920688272 27902688 19540544
02:12:38 9935 8192000000 9874374304 10210660352 933012432 10.224 100 84 165 1 986742368 36419024 17310912
02:13:52 6786 8192000000 9939474784 10285408256 960782144 10.497 100 86 170 1 1047068624 68283808 18002672
02:15:01 2299 8192000000 9962678096 10316759040 992984096 10.810 100 91 173 1 1074288064 63054736 18249232
02:16:06 1181 8192000000 9986164320 10347446272 1016172240 11.035 100 88 173 0 1106834848 73473504 17189104
02:17:10 1657 8192000000 9998746176 10367594496 1033282592 11.200 100 84 161 1 1124630512 75851984 15495936
02:18:13 894 8192000000 10037276144 10409267200 1057866320 11.436 100 97 191 0 1154737808 78490912 18380576
02:19:16 921 8192000000 10035109456 10409725952 1068358912 11.536 100 98 185 1 1148647376 62323680 17964784
02:20:18 1638 8192000000 10044237824 10422509568 1072531824 11.576 100 90 172 0 1163654576 73447392 17675360
02:21:21 941 8192000000 10058657440 10439008256 1077746176 11.626 100 94 182 0 1179094928 82643856 18704896
02:22:26 1112 8192000000 10072157056 10458869760 1091497872 11.757 100 98 190 0 1196447088 86041568 18907648
02:23:29 1242 8192000000 10064309344 10452340736 1091786432 11.760 100 97 189 3 1182985664 73202064 17997168
02:24:31 1302 8192000000 10085486000 10474876928 1098423840 11.823 100 99 194 2 1207651136 88897584 20329712
02:25:34 1214 8192000000 10072145680 10463207424 1103240736 11.868 100 95 180 0 1201994624 79974272 18779616
02:26:37 1129 8192000000 10066176736 10455515136 1106190464 11.896 100 95 183 0 1190250592 64640272 19419856
02:27:39 1405 8192000000 10079039760 10470031360 1104498304 11.880 100 89 164 2 1199362928 78707296 16157328
02:28:42 1090 8192000000 10065690192 10456072192 1107798096 11.912 100 97 189 0 1189339968 62134384 19407488
02:29:45 1269 8192000000 10074023920 10471100416 1100486192 11.842 100 79 151 1 1196080496 80155360 15438944
02:30:47 1247 8192000000 10044706224 10429779968 1071361840 11.565 100 95 178 1 1170411024 80901968 18147216
02:31:49 1272 8192000000 10024612640 10408665088 1070949200 11.561 99 73 142 1 1146855136 61201440 14704496
02:32:51 3192 8192000000 10008519936 10386276352 1051051888 11.371 99 84 150 0 1128688256 62125584 15510784
02:33:55 2050 8192000000 10025493632 10409488384 1064551088 11.500 99 72 135 1 1139320288 59699824 15069376
02:34:58 895 8192000000 9993429376 10374316032 1052483216 11.384 100 83 161 0 1117216864 46893312 17840336
02:36:00 985 8192000000 10010848368 10385473536 1050283520 11.363 100 97 188 5 1136916720 67799584 18833616
02:37:02 3579 8192000000 10018034368 10401062912 1051098752 11.371 100 82 157 2 1131634528 64509984 16025792
02:38:14 1198 8192000000 10016080480 10394464256 1059213632 11.449 100 90 166 1 1137932432 62127168 16591632
02:39:21 6119 8192000000 10033305088 10412040192 1055428208 11.413 100 81 152 4 1152381632 81861696 15091728
02:40:31 3822 8192000000 10046798976 10434314240 1074734992 11.597 100 67 128 0 1161601440 74070864 12795584
02:41:37 3321 8192000000 10053345376 10455584768 1087927472 11.723 100 67 129 2 1175603696 74995552 12680672
02:42:45 1192 8192000000 10084794080 10472255488 1091044144 11.753 100 90 173 2 1204544928 94436576 19064208
02:43:49 888 8192000000 10138357472 10525134848 1106316528 11.898 100 95 187 0 1257583376 131444928 19821920
02:44:53 2991 8192000000 10153807456 10552815616 1120778848 12.034 100 75 145 0 1272523024 135452928 16291248
02:46:04 9697 8192000000 10184472864 10596081664 1139671344 12.212 100 80 157 1 1300444480 143234960 17538176
02:47:31 1062 8192000000 10223097696 10616090624 1157800352 12.383 100 90 171 0 1333360848 157631936 17928560
02:48:37 2236 8192000000 10283597440 10687262720 1169511520 12.492 100 81 157 2 1395572736 208044736 18016480
02:49:52 1568 8192000000 10329589168 10735575040 1179153952 12.582 100 86 163 1 1433992336 236452560 18385824
02:50:57 1209 8192000000 10378534736 10776854528 1193699056 12.718 100 98 189 1 1487362192 272380544 21282592
02:52:02 1091 8192000000 10420441968 10824130560 1200354128 12.780 100 94 180 1 1529433328 308133376 20945824
02:53:05 1576 8192000000 10443726112 10869760000 1198959168 12.767 100 90 173 0 1552256256 332876144 20420944
02:54:12 980 8192000000 10472480096 10883309568 1198352640 12.761 100 95 183 2 1578383488 358442192 21588656
02:55:14 1009 8192000000 10503404400 10921336832 1204904560 12.822 100 96 186 1 1608803632 382464992 21434080
02:56:18 1285 8192000000 10483814912 10923003904 1200767712 12.783 100 95 186 1 1589292752 366525200 21999840
02:57:24 1157 8192000000 10511854768 10932502528 1200458944 12.781 100 95 187 0 1618906240 395884480 22562816
02:58:27 918 8192000000 10518452672 10965569536 1190280160 12.686 100 94 184 1 1623279872 411374208 21625504
02:59:31 2569 8192000000 10504066528 10933559296 1187151856 12.657 100 73 146 2 1605864480 401269840 17442784
03:00:38 3678 8192000000 10532973776 10977316864 1186016592 12.646 100 64 126 0 1642780432 440968480 15795360
03:01:45 3719 8192000000 10563427008 10989907968 1172203616 12.517 100 58 114 2 1673805136 487079584 14521936
03:03:01 4004 8192000000 10569715376 11048255488 1166941168 12.468 100 63 123 1 1690771664 507864096 15966400
03:04:08 9603 8192000000 10493158480 11028951040 1166681504 12.466 100 49 95 1 1651566816 471939024 12946288
03:05:21 11006 8192000000 10408157600 10906394624 1152778672 12.336 99 40 80 1 1511767776 349249952 9739152
03:06:43 17977 8192000000 10377705360 10831810560 1157258656 12.378 100 24 46 1 1480711808 317134112 6319040
03:08:06 34374 8192000000 10318600960 10768384000 1153656208 12.344 100 16 32 0 1470125328 311900368 4568752
03:09:41 74388 8192000000 10066707312 10547580928 1102459200 11.861 96 16 30 1 1150836112 45032256 3344656
03:11:57 125624 8192000000 10193795568 10638241792 1145092304 12.263 99 39 78 0 1247725360 94417824 8215232
03:15:08 58438 8192000000 10143907072 10629615616 1148161376 12.292 100 26 50 0 1252526512 99184096 5181040
03:17:08 55099 8192000000 10111996864 10583359488 1134330704 12.162 98 35 68 3 1201233424 59507856 7394864
03:19:19 77014 8192000000 10117160592 10578178048 1131570544 12.136 98 17 34 1 1202308304 67106496 3631264
03:21:37 24623 8192000000 10084380032 10553679872 1143877328 12.252 100 10 20 0 1200988944 55135504 1976112
03:23:03 13681 8192000000 10091497840 10564243456 1129909872 12.121 100 21 43 0 1210557152 76171136 4476144
03:24:18 74239 8192000000 10101382336 10570555392 1133869552 12.158 99 25 50 1 1200433664 61613264 4950848
03:26:45 50997 8192000000 10126955856 10587422720 1141993872 12.234 99 17 34 1 1228656192 83174832 3487488
03:28:46 56253 8192000000 10059798528 10512855040 1136616928 12.184 99 15 30 0 1176327888 36501312 3209648
03:30:44 55379 8192000000 10146312208 10602373120 1145781472 12.270 99 35 70 1 1238533136 84850416 7901248
03:32:43 51940 8192000000 10171335184 10628808704 1141522976 12.230 98 23 46 0 1254493056 107550240 5419840
03:34:36 30854 8192000000 10208903584 10689499136 1168230752 12.480 100 38 76 2 1322482224 145563024 8688448
03:36:11 32402 8192000000 10243933888 10686779392 1151376336 12.322 99 41 80 2 1315401760 154710416 9315008
03:37:49 31428 8192000000 10169185376 10615480320 1135851296 12.176 98 38 75 0 1250483824 106691168 7941360
03:39:25 55209 8192000000 10087883552 10544398336 1125062640 12.075 98 37 74 1 1171575856 39081536 7431680
03:41:25 80589 8192000000 10092752784 10538348544 1126226816 12.086 98 42 77 1 1184586560 50551696 7808048
03:43:54 25488 8192000000 10108944784 10551328768 1137224208 12.189 99 33 63 1 1195884320 52278096 6382016
03:45:20 64485 8192000000 10056122192 10518110208 1116225600 11.991 98 17 34 0 1146777312 27069504 3482208
03:47:30 67580 8192000000 10055489200 10533220352 1100536192 11.843 97 8 16 1 1139788048 37454352 1797504
03:49:43 75333 8192000000 10093603152 10578669568 1122029456 12.046 99 14 28 1 1192061024 66880928 3150640
03:51:59 67343 8192000000 10071952032 10531819520 1082472144 11.671 96 16 32 1 1155226848 69330224 3424480
03:54:16 38763 8192000000 10089573952 10523500544 1098905536 11.827 98 36 72 0 1166366224 59888928 7571760
03:55:59 43240 8192000000 10109401824 10578976768 1105980464 11.894 98 34 68 0 1215688144 102456768 7250912
03:57:44 30742 8192000000 10091168928 10529173504 1085751296 11.702 98 30 60 1 1163742272 72040992 5949984
03:59:26 46808 8192000000 10072137056 10530492416 1110113680 11.933 100 35 70 0 1183770192 67144064 6512448
04:01:14 12998 8192000000 10081158848 10508484608 1134116288 12.160 100 55 105 1 1171162400 26675776 10370336
04:02:28 87620 8192000000 10097601840 10574118912 1101466944 11.852 97 35 70 2 1211241856 102456928 7317984
04:04:58 28621 8192000000 10059145792 10498187264 1128829168 12.110 100 69 135 1 1164504528 18510512 17164848
04:06:54 28905 8192000000 10103060560 10562150400 1139098416 12.207 100 33 66 1 1216180880 70423248 6659216
04:08:27 64454 8192000000 10136241728 10588241920 1151832272 12.327 100 16 32 0 1233960032 78071296 4056464
04:10:36 114877 8192000000 10190071456 10656591872 1154795152 12.354 100 18 37 0 1289129568 129851488 4482928
04:13:54 40488 8192000000 10106769712 10551709696 1120133504 12.028 97 23 45 0 1195679664 70439824 5106336
04:15:35 59256 8192000000 10122389360 10568794112 1107608624 11.910 96 22 44 0 1174922880 62491856 4822400
04:17:44 36562 8192000000 10165953024 10616197120 1140749008 12.223 99 34 67 2 1241581120 93290704 7541408
04:19:21 10762 8192000000 10223308528 10651172864 1144727088 12.260 99 53 106 0 1300309248 143954672 11627488
04:20:47 58269 8192000000 10157634224 10625871872 1132922128 12.149 98 26 52 1 1303899360 165189552 5787680
04:22:51 58354 8192000000 10084868352 10635112448 1134907696 12.168 100 15 30 1 1235561920 97324688 3329536
04:24:50 140591 8192000000 10089496784 10568048640 1141289280 12.228 99 21 42 2 1212260960 66239776 4731904
04:28:16 75158 8192000000 10068027472 10546479104 1121045328 12.037 98 7 14 0 1144759664 22857712 856624
04:30:36 93344 8192000000 10138396800 10620489728 1133882784 12.158 98 19 38 1 1190678736 53218560 3577392
04:33:27 71564 8192000000 10104262992 10597175296 1121909536 12.045 97 22 42 1 1189123168 62249856 4963776
04:35:49 94345 8192000000 9676456176 10103119872 745763424 8.343 65 10 20 1 795304704 47126336 2414944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-04-2022 04:38:51 to 10-04-2022 04:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

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

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          214100
MON$OLDEST_ACTIVE               214423
MON$OLDEST_SNAPSHOT             214423
MON$NEXT_TRANSACTION            214423
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                       2049312
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Apr 10 04:37:42 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
--- end of diff output ---

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

10.04.22 04:39:11. 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-04-10 04:39:14 No severe PSQL-related problems occured
10.04.22 04:39:15. End of report.