Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.05.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 15660084 kB
MemAvailable 31374912 kB
Buffers 20 kB
Cached 15951820 kB
SwapCached 2632 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-05-18 04:37:16
DTS_END                         2022-05-18 04:37:16.5560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40310
TRN_ID                          223226
ATT_ID                          652
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: 9038.92 24245 1084670
customer order: creation 176.68 476 21202
customer order: refuse 15.32 487 1838
order to supplier: creation 46.68 1460 5601
order to supplier: removal 3.20 196887 384
invoice (draft): creation 46.88 3514 5625
invoice (draft): removal 48.95 1177 5874
invoice accept: apply 42.73 26615 5127
invoice accept: cancel 43.73 1435 5248
customer reserve: creation 3964.21 249 475705
customer reserve: removal 4354.36 112 522523
realization accept: apply 86.05 336 10326
realization accept: cancel 78.08 164 9369
payment from customer: creation 61.19 205 7343
payment from customer: removal 5.83 139 700
payment to supplier: creation 48.65 226 5838
payment to supplier: removal 3.28 141 393
service: total inventory turnovers 3.97 4670 476
service: total monetary turnovers 8.06 349 967
service: aggregate perf. data 1.06 16682 127
service: refresh index statistics 0.03 229580 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11202
TEST_TIME 2 6836
TEST_TIME 3 5790
TEST_TIME 4 5394
TEST_TIME 5 5360
TEST_TIME 6 5309
TEST_TIME 7 5291
TEST_TIME 8 5380
TEST_TIME 9 5475
TEST_TIME 10 5570
TEST_TIME 11 5932
TEST_TIME 12 6006
TEST_TIME 13 6382
TEST_TIME 14 6435
TEST_TIME 15 6682
TEST_TIME 16 6827
TEST_TIME 17 6839
TEST_TIME 18 7187
TEST_TIME 19 7199
TEST_TIME 20 7404
TEST_TIME 21 7624
TEST_TIME 22 7811
TEST_TIME 23 7992
TEST_TIME 24 8312
TEST_TIME 25 8602
TEST_TIME 26 8603
TEST_TIME 27 8666
TEST_TIME 28 8642
TEST_TIME 29 8577
TEST_TIME 30 8954
TEST_TIME 31 8959
TEST_TIME 32 9112
TEST_TIME 33 9083
TEST_TIME 34 8879
TEST_TIME 35 9051
TEST_TIME 36 9486
TEST_TIME 37 9628
TEST_TIME 38 9779
TEST_TIME 39 10203
TEST_TIME 40 10293
TEST_TIME 41 10458
TEST_TIME 42 10800
TEST_TIME 43 10812
TEST_TIME 44 10718
TEST_TIME 45 10578
TEST_TIME 46 10549
TEST_TIME 47 10446
TEST_TIME 48 10402
TEST_TIME 49 10390
TEST_TIME 50 10282
TEST_TIME 51 10251
TEST_TIME 52 10085
TEST_TIME 53 9901
TEST_TIME 54 9903
TEST_TIME 55 9835
TEST_TIME 56 9732
TEST_TIME 57 9627
TEST_TIME 58 9597
TEST_TIME 59 9583
TEST_TIME 60 9527
TEST_TIME 61 9492
TEST_TIME 62 9519
TEST_TIME 63 9477
TEST_TIME 64 9427
TEST_TIME 65 9548
TEST_TIME 66 9496
TEST_TIME 67 9517
TEST_TIME 68 9593
TEST_TIME 69 9574
TEST_TIME 70 9503
TEST_TIME 71 9487
TEST_TIME 72 9550
TEST_TIME 73 9615
TEST_TIME 74 9570
TEST_TIME 75 9593
TEST_TIME 76 9611
TEST_TIME 77 9594
TEST_TIME 78 9581
TEST_TIME 79 9591
TEST_TIME 80 9544
TEST_TIME 81 9445
TEST_TIME 82 9462
TEST_TIME 83 9412
TEST_TIME 84 9347
TEST_TIME 85 9351
TEST_TIME 86 9309
TEST_TIME 87 9268
TEST_TIME 88 9207
TEST_TIME 89 9207
TEST_TIME 90 9136
TEST_TIME 91 9135
TEST_TIME 92 9227
TEST_TIME 93 9199
TEST_TIME 94 9234
TEST_TIME 95 9249
TEST_TIME 96 9263
TEST_TIME 97 9219
TEST_TIME 98 9287
TEST_TIME 99 9299
TEST_TIME 100 9322
TEST_TIME 101 9317
TEST_TIME 102 9300
TEST_TIME 103 9311
TEST_TIME 104 9341
TEST_TIME 105 9358
TEST_TIME 106 9432
TEST_TIME 107 9382
TEST_TIME 108 9325
TEST_TIME 109 9313
TEST_TIME 110 9259
TEST_TIME 111 9271
TEST_TIME 112 9284
TEST_TIME 113 9247
TEST_TIME 114 9269
TEST_TIME 115 9229
TEST_TIME 116 9189
TEST_TIME 117 9148
TEST_TIME 118 9109
TEST_TIME 119 9123
TEST_TIME 120 9064

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-05-2022 04:38:40 to 18-05-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 1522273 1522273 0 0.00 0 21501 15 0 0
doc_list_biud 1522257 1522257 0 0.00 0 39927 45 0 0
sp_add_invoice_to_stock 5198 5127 71 1.36 39 209214 26615 0 71
sp_cancel_adding_invoice 5248 5248 0 0.00 7 79233 1436 0 0
sp_cancel_client_order 1838 1838 0 0.00 7 12546 487 0 0
sp_cancel_customer_reserve 522523 522523 0 0.00 1 21604 113 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 5368 139 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 3051 142 0 0
sp_cancel_supplier_invoice 5879 5874 5 0.08 3 11440 1178 0 5
sp_cancel_supplier_order 384 384 0 0.00 10 1685529 196888 0 0
sp_cancel_write_off 9369 9369 0 0.00 2 8593 165 0 0
sp_client_order 21202 21202 0 0.00 6 17555 477 0 0
sp_customer_reserve 475705 475705 0 0.00 1 171733 250 0 0
sp_fill_shopping_cart 32418 32418 0 0.00 1 18619 90 0 0
sp_fill_shopping_cart_clo_res 475676 475676 0 0.00 0 21196 18 0 0
sp_get_clo_for_invoice 5085 5085 0 0.00 0 17850 260 0 0
sp_kill_cost_storno 15710 15710 0 0.00 0 14993 86 0 0
sp_kill_qstorno_ret_qs2qd 1060673 1060673 0 0.00 0 21512 48 0 0
sp_kill_qty_storno 560644 560644 0 0.00 0 39927 113 0 0
sp_lock_selected_doc 21247 21247 0 0.00 0 13659 8 0 0
sp_make_cost_storno 28592 28592 0 0.00 0 13918 94 0 0
sp_multiply_rows_for_pdistr 28590 28590 0 0.00 0 10108 47 0 0
sp_multiply_rows_for_qdistr 21202 21202 0 0.00 0 15457 159 0 0
sp_pay_from_customer 7343 7343 0 0.00 6 17728 206 0 0
sp_pay_to_supplier 5838 5838 0 0.00 6 17274 227 0 0
sp_payment_common 13179 13179 0 0.00 2 9272 51 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 1 11808 400 0 0
sp_qd_handle_on_invoice_upd_sts 10332 10332 0 0.00 0 39926 643 0 0
sp_qd_handle_on_reserve_upd_sts 10324 10324 0 0.00 0 21321 87 0 0
sp_reserve_write_off 10326 10326 0 0.00 9 21392 336 0 0
sp_supplier_invoice 5625 5625 0 0.00 11 93685 3514 0 0
sp_supplier_order 5601 5601 0 0.00 6 28685 1460 0 0
srv_aggregate_perf_data 127 127 0 0.00 2015 176628 16683 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 712 166079 32911 0 0
srv_find_qd_qs_mism 1034334 1034334 0 0.00 0 21498 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 72 81421 4670 0 0
srv_make_money_saldo 967 967 0 0.00 1 8012 349 0 0
srv_recalc_idx_stat 4 4 0 0.00 45416 379240 229581 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5131 5131 0 0.00 1 12997 1097 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 5613 530 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 2663 33 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 703 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1715 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1685 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 2788 43 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 1 120016 9588 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 928 22 0 0
t$perf-norm:sp_client_order 21202 21202 0 0.00 0 4437 40 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 3689 20 0 0
t$perf-norm:sp_pay_from_customer 7344 7344 0 0.00 0 4779 20 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 5342 23 0 0
t$perf-norm:sp_reserve_write_off 10326 10326 0 0.00 0 4485 27 0 0
t$perf-norm:sp_supplier_invoice 5625 5625 0 0.00 0 7436 34 0 0
t$perf-norm:sp_supplier_order 5601 5601 0 0.00 0 7600 39 0 0
t$perf-norm:srv_aggregate_perf_data 127 127 0 0.00 0 1454 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1273 22 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 3964 26 0 0
t$perf-norm:srv_make_money_saldo 967 967 0 0.00 0 799 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 193 75 0 0
v_add_invoice_to_stock 10298 10156 142 1.37 0 5153 24 0 142
v_all_customers 38606 38606 0 0.00 0 141 4 0 0
v_all_suppliers 31109 31109 0 0.00 0 119 5 0 0
v_all_wares 42399 42399 0 0.00 0 122 4 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 1617 18 0 0
v_cancel_client_order 3676 3676 0 0.00 0 3466 13 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2218 13 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 1 521 12 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 2426 15 0 0
v_cancel_supplier_invoice 868 858 10 1.15 0 872 25 0 10
v_cancel_supplier_order 768 768 0 0.00 0 907 14 0 0
v_cancel_write_off 2034 2034 0 0.00 0 891 23 0 0
v_max_id_clo_ord 5597 5597 0 0.00 0 457 6 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 2942 17 0 0
v_max_id_ord_sup 5620 5620 0 0.00 0 2384 6 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 146 9 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 1721 8 0 0
v_min_id_clo_ord 5597 5597 0 0.00 0 4692 9 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 685 8 0 0
v_min_id_ord_sup 5620 5620 0 0.00 0 4454 9 0 0
v_min_non_paid_invoice 5835 5835 0 0.00 0 4603 14 0 0
v_min_non_paid_realizn 7342 7342 0 0.00 0 9256 22 0 0
v_our_firm 10636 10636 0 0.00 0 66 5 0 0
v_reserve_write_off 20648 20648 0 0.00 0 4456 12 0 0
x_lock_depdocs_on_canc_invoice 10693 10693 0 0.00 0 12814 129 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 3894 398 0 0
x_make_qstorno_1000_1200 5601 5601 0 0.00 2 28655 1250 0 0
x_make_qstorno_1200_2000 5625 5625 0 0.00 5 93652 3291 0 0
x_make_qstorno_2100_3300 475704 475704 0 0.00 0 171732 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-05-2022 04:38:45 to 18-05-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:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 21413 8192000000 9732686096 10034237440 811692128 9.015 100 86 172 0 839258048 7320032 20245888
02:09:52 35856 8192000000 9777369824 10089500672 849396928 9.394 100 88 175 1 882493728 12658176 20438624
02:11:31 15298 8192000000 9806164128 10129932288 873708544 9.637 100 80 160 0 911420432 19843472 17868416
02:13:00 17340 8192000000 9873769440 10206449664 916007536 10.057 100 86 172 1 987447280 52547024 18892720
02:14:19 5504 8192000000 9914594624 10259861504 953600048 10.426 100 85 164 1 1034226224 63440624 17185552
02:15:28 2038 8192000000 9962148608 10316660736 994526128 10.825 100 92 182 1 1069983312 56607360 18849824
02:16:34 3821 8192000000 10004061216 10364977152 1015278000 11.026 100 85 161 1 1118720864 87225600 16217264
02:17:39 906 8192000000 9999963616 10367397888 1048973040 11.351 100 94 181 2 1118345968 51237104 18135824
02:18:42 1081 8192000000 10023374768 10399895552 1059411456 11.451 100 96 185 2 1140922880 62454912 19056512
02:19:44 1882 8192000000 10031708480 10415222784 1067705184 11.530 100 89 168 0 1151696752 66379488 17612080
02:20:48 1305 8192000000 10059960528 10441609216 1076892832 11.618 100 98 187 1 1178530016 82733024 18904160
02:21:51 1006 8192000000 10046317984 10438193152 1082742912 11.674 100 92 172 1 1170311328 68932240 18636176
02:22:53 1175 8192000000 10036339648 10420408320 1089443744 11.737 100 93 177 1 1157973376 49879712 18649920
02:23:59 874 8192000000 10067239520 10454982656 1089965424 11.742 100 91 176 0 1188597024 79773920 18857680
02:25:01 916 8192000000 10044652528 10433884160 1091981344 11.761 100 85 161 1 1164569680 56143056 16445280
02:26:03 1256 8192000000 10043064816 10433503232 1088910896 11.732 100 96 183 2 1164599184 56486624 19201664
02:27:05 1305 8192000000 10074918368 10464174080 1096188448 11.801 100 92 181 1 1195566288 80165168 19212672
02:28:08 1307 8192000000 10066964064 10463203328 1100283376 11.840 100 85 161 0 1186993248 70509824 16200048
02:29:11 1078 8192000000 10076907648 10470195200 1093440960 11.775 100 95 181 1 1197142544 85807520 17894064
02:30:13 1078 8192000000 10044143920 10429714432 1081069376 11.658 100 88 170 1 1166221376 68613136 16538864
02:31:16 1142 8192000000 10026042912 10407309312 1083192016 11.678 100 97 190 3 1151519680 48766288 19561376
02:32:17 10669 8192000000 10002622448 10392387584 1029513472 11.164 96 68 136 2 1091279600 48216688 13549440
02:33:29 3788 8192000000 10022943808 10411597824 1050776592 11.368 100 61 121 1 1138532592 75506240 12249760
02:34:34 950 8192000000 10030668656 10409414656 1058459840 11.442 100 95 188 1 1152346240 73396736 20489664
02:35:37 1153 8192000000 10026193216 10400555008 1043088512 11.294 100 93 183 0 1141424368 79404928 18930928
02:36:40 1273 8192000000 9994179968 10371166208 1045268848 11.315 100 92 172 2 1115881152 52984256 17628048
02:37:43 1210 8192000000 10000467712 10376155136 1051478928 11.375 100 90 171 0 1123124544 54760768 16884848
02:38:51 18697 8192000000 10009796832 10394054656 1076926976 11.618 100 62 118 0 1137564960 47970576 12667408
02:40:17 1086 8192000000 10055332432 10435592192 1094591152 11.786 100 86 163 0 1172159728 61648112 15920464
02:41:22 1474 8192000000 10075617152 10462703616 1108591632 11.919 100 73 132 1 1192218640 70782176 12844832
02:42:28 9714 8192000000 10120180176 10519961600 1117186896 12.000 100 69 138 0 1243154864 111399280 14568688
02:43:44 13453 8192000000 10167698304 10561736704 1130273248 12.124 100 68 131 1 1283633648 139664384 13696016
02:45:03 1662 8192000000 10208616624 10600992768 1145279600 12.265 100 90 172 3 1326419808 162573280 18566928
02:46:11 1225 8192000000 10247949552 10641379328 1154347296 12.350 100 98 186 0 1362764384 188310672 20106416
02:47:16 9234 8192000000 10289182672 10701725696 1155065376 12.357 100 73 146 0 1399171840 227389648 16716816
02:48:31 2422 8192000000 10313597264 10720456704 1166088832 12.460 100 85 163 0 1423895920 239754624 18052464
02:49:39 1529 8192000000 10338088576 10753757184 1173173232 12.526 100 86 164 1 1448056336 256528112 18354992
02:50:45 4076 8192000000 10305506592 10734739456 1157914464 12.384 99 79 154 2 1412332656 237052832 17365360
02:51:55 993 8192000000 10352543360 10768859136 1179143648 12.582 100 98 191 1 1465132912 263096976 22892288
02:52:58 1032 8192000000 10366424512 10784641024 1170704080 12.503 100 96 183 1 1480091744 288317664 21070000
02:54:02 1272 8192000000 10351597808 10779852800 1166447584 12.464 99 92 175 2 1457909088 272609840 18851664
02:55:12 1190 8192000000 10402118160 10827206656 1189231328 12.676 100 96 185 0 1506867856 296820752 20815776
02:56:19 788 8192000000 10400273520 10826338304 1187759488 12.663 100 97 182 2 1513507600 303860560 21887552
02:57:22 1150 8192000000 10402249616 10847223808 1176358704 12.556 99 96 188 0 1510293024 312050912 21883408
02:58:26 1029 8192000000 10438883936 10856521728 1189715216 12.681 100 95 181 4 1547214752 335928176 21571360
02:59:28 899 8192000000 10471815792 10889695232 1182976560 12.618 100 97 191 1 1576206512 370214480 23015472
03:00:34 866 8192000000 10433636400 10864971776 1180062080 12.591 100 95 187 0 1540188704 338759312 21367312
03:01:38 746 8192000000 10373967680 10845011968 1171986080 12.515 100 92 177 4 1478440272 285919280 20534912
03:02:41 726 8192000000 10405693824 10849370112 1165824912 12.458 100 97 189 2 1508507168 319923936 22758320
03:03:43 5999 8192000000 10381436400 10832232448 1156641152 12.372 100 55 108 0 1496876960 326907856 13327952
03:04:59 2069 8192000000 10434787952 10872344576 1179793504 12.588 100 68 129 0 1539070880 343488976 15788400
03:06:08 2009 8192000000 10445325296 10908368896 1177480672 12.567 100 84 162 0 1547168032 350506608 19180752
03:07:14 902 8192000000 10468100368 10898759680 1185634160 12.643 100 95 187 0 1572663824 365512624 21517040
03:08:20 5172 8192000000 10500793728 10934931456 1195350864 12.733 100 54 108 0 1603256064 394244496 13660704
03:09:37 703 8192000000 10503401456 10947620864 1201661200 12.792 100 84 165 3 1604006176 382546448 19798528
03:10:40 6413 8192000000 10526964720 10982506496 1196991920 12.748 100 53 105 1 1628945024 417849408 14103696
03:11:52 4985 8192000000 10592173104 11032682496 1190980320 12.692 100 53 105 0 1689949136 484452192 14516624
03:12:58 7620 8192000000 10477132384 11001024512 1183930000 12.627 100 54 105 0 1594183984 397050992 13202992
03:14:08 23371 8192000000 10437065056 10909888512 1170779440 12.504 99 33 66 0 1557785984 378280752 8725792
03:15:43 40237 8192000000 10343130512 10881560576 1149476400 12.305 98 33 66 1 1470345360 312361664 8507296
03:17:48 16368 8192000000 10237027520 10747420672 1150108848 12.311 100 20 41 2 1359918496 204497552 5312096
03:19:07 89599 8192000000 10057564464 10527006720 1089522832 11.738 95 10 16 2 1173569856 81297520 2749504
03:21:38 20304 8192000000 10076173648 10521964544 1124269920 12.067 100 29 56 1 1168302480 38408624 5623936
03:23:20 52576 8192000000 10082273808 10510340096 1145741776 12.270 100 52 92 1 1202839776 46881616 10216384
03:25:17 67459 8192000000 10076264176 10528907264 1133549856 12.155 99 15 28 0 1161264528 24362976 3351696
03:27:26 105666 8192000000 10096107776 10572099584 1125278656 12.077 98 11 22 0 1165808544 38368736 2161152
03:30:17 49134 8192000000 10110076048 10563784704 1138372608 12.200 100 54 101 0 1191473936 41318304 11783024
03:32:16 65719 8192000000 10096772816 10533593088 1108572192 11.919 97 37 72 1 1164059488 48023376 7463920
03:34:27 29927 8192000000 10092121856 10537029632 1126483232 12.088 99 22 43 1 1180194160 49097632 4613296
03:35:57 19666 8192000000 10130006416 10570555392 1143986800 12.253 100 41 83 0 1237283456 84422176 8874480
03:37:20 16615 8192000000 10163422832 10601308160 1155831088 12.364 100 41 81 2 1252974048 88400272 8742688
03:38:39 2062 8192000000 10194512576 10610708480 1150365280 12.313 100 86 165 3 1285380688 117129152 17886256
03:39:42 166078 8192000000 10183974464 10679255040 1136112720 12.179 98 26 49 1 1260446016 118865232 5468064
03:44:29 48994 8192000000 10126576800 10633011200 1116711152 11.996 97 12 24 0 1207436800 87716096 3009552
03:46:29 39456 8192000000 10077543248 10526765056 1130663120 12.128 99 24 48 1 1169930496 33627648 5639728
03:48:14 24889 8192000000 10135741040 10576642048 1129247968 12.114 99 55 106 3 1230539040 90597456 10693616
03:49:45 81079 8192000000 10047274144 10511437824 1060493600 11.461 94 14 28 0 1133412112 69934048 2984464
03:52:12 46523 8192000000 10104240768 10573500416 1110163520 11.934 99 28 54 2 1180932032 64787328 5981184
03:53:59 91036 8192000000 10060633632 10486624256 1051198864 11.372 93 17 32 0 1083349728 28628000 3522864
03:56:31 48852 8192000000 10077683744 10522624000 1093491216 11.776 98 32 63 4 1167254128 67147456 6615456
03:58:20 104783 8192000000 10073238960 10526318592 1082157392 11.668 97 27 53 1 1162781232 74939728 5684112
04:01:12 46353 8192000000 10049115456 10517966848 1099065712 11.829 99 24 48 2 1162695760 57836560 5793488
04:02:59 18046 8192000000 10071592368 10509533184 1105843376 11.893 99 50 92 1 1167057248 52315936 8897936
04:04:20 63780 8192000000 10114281072 10557968384 1084915360 11.694 96 14 28 1 1177944384 89631744 3397280
04:06:27 78857 8192000000 10126033760 10605916160 1113375904 11.964 99 14 28 0 1211887664 95311760 3200000
04:08:47 46922 8192000000 10118297840 10549379072 1117411504 12.003 99 30 59 0 1222288224 98141536 6735184
04:10:38 86944 8192000000 10077757056 10571931648 1113447728 11.965 98 26 52 2 1212745904 93926288 5371888
04:13:09 48634 8192000000 10085277088 10520154112 1091918112 11.761 96 29 59 2 1139379632 41606624 5854896
04:15:00 38586 8192000000 10111411072 10611986432 1137252256 12.190 100 38 76 1 1226292096 81597664 7442176
04:16:38 47909 8192000000 10103091584 10564751360 1128866400 12.111 99 31 62 0 1205138384 69625776 6646208
04:18:48 61899 8192000000 10106552528 10541277184 1082271600 11.669 96 19 38 0 1118919728 33133072 3515056
04:20:55 64766 8192000000 10120851744 10585079808 1112764512 11.959 98 18 36 0 1214669616 98016928 3888176
04:23:01 99242 8192000000 10064360656 10545549312 1100771056 11.845 98 14 28 0 1163060464 58367184 3922224
04:26:37 59241 8192000000 10110748288 10549559296 1125158560 12.076 99 41 80 0 1193525328 59678560 8688208
04:28:37 57120 8192000000 10108142176 10613420032 1116220976 11.991 98 20 39 2 1203397696 82443104 4733616
04:30:39 41774 8192000000 10097379936 10548326400 1131367968 12.134 99 21 42 0 1182523504 46776816 4378720
04:32:22 41652 8192000000 10055297744 10515734528 1112484416 11.956 98 23 45 1 1138479824 21191888 4803520
04:34:05 29960 8192000000 10070271904 10545713152 1136537776 12.183 100 6 12 1 1174843440 37124624 1181040
04:35:35 107802 8192000000 10003161456 10509107200 1079962480 11.647 95 4 8 0 1123718496 43048896 707120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-05-2022 04:39:08 to 18-05-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          223543
MON$OLDEST_ACTIVE               223544
MON$OLDEST_SNAPSHOT             223544
MON$NEXT_TRANSACTION            223544
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                       2123008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
--- end of diff output ---

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

18.05.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-05-18 04:39:14 No severe PSQL-related problems occured
18.05.22 04:39:15. End of report.