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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16360160 kB
MemAvailable 31467020 kB
Buffers 0 kB
Cached 15381744 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 10:37:20
DTS_END                         2022-05-18 10:37:20.1110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(50:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43984
TRN_ID                          220215
ATT_ID                          654
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: 8908.65 23029 1069038
customer order: creation 171.36 481 20563
customer order: refuse 14.15 456 1698
order to supplier: creation 45.88 1445 5505
order to supplier: removal 3.63 171112 435
invoice (draft): creation 45.50 3193 5460
invoice (draft): removal 49.85 1151 5982
invoice accept: apply 41.33 27968 4959
invoice accept: cancel 45.10 1331 5412
customer reserve: creation 3790.87 269 454904
customer reserve: removal 4407.96 109 528955
realization accept: apply 83.62 311 10034
realization accept: cancel 80.34 184 9641
payment from customer: creation 58.69 194 7043
payment from customer: removal 6.15 150 738
payment to supplier: creation 48.18 201 5781
payment to supplier: removal 3.58 157 429
service: total inventory turnovers 4.33 3892 519
service: total monetary turnovers 7.25 393 870
service: aggregate perf. data 0.88 16616 106
service: refresh index statistics 0.03 230960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-05-2022 10:39:53 to 18-05-2022 10:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12369
TEST_TIME 2 8155
TEST_TIME 3 7243
TEST_TIME 4 6963
TEST_TIME 5 6656
TEST_TIME 6 6922
TEST_TIME 7 6999
TEST_TIME 8 6782
TEST_TIME 9 6540
TEST_TIME 10 6452
TEST_TIME 11 6539
TEST_TIME 12 6623
TEST_TIME 13 6689
TEST_TIME 14 6766
TEST_TIME 15 6695
TEST_TIME 16 6781
TEST_TIME 17 6777
TEST_TIME 18 6853
TEST_TIME 19 6818
TEST_TIME 20 6935
TEST_TIME 21 7290
TEST_TIME 22 7229
TEST_TIME 23 7233
TEST_TIME 24 7224
TEST_TIME 25 7444
TEST_TIME 26 7720
TEST_TIME 27 7742
TEST_TIME 28 8422
TEST_TIME 29 8827
TEST_TIME 30 9244
TEST_TIME 31 9813
TEST_TIME 32 10109
TEST_TIME 33 10344
TEST_TIME 34 10615
TEST_TIME 35 10964
TEST_TIME 36 10847
TEST_TIME 37 10747
TEST_TIME 38 10530
TEST_TIME 39 10371
TEST_TIME 40 10658
TEST_TIME 41 10732
TEST_TIME 42 10503
TEST_TIME 43 10288
TEST_TIME 44 10253
TEST_TIME 45 10125
TEST_TIME 46 10011
TEST_TIME 47 9937
TEST_TIME 48 9873
TEST_TIME 49 9857
TEST_TIME 50 9905
TEST_TIME 51 9926
TEST_TIME 52 9833
TEST_TIME 53 9792
TEST_TIME 54 9712
TEST_TIME 55 9618
TEST_TIME 56 9464
TEST_TIME 57 9447
TEST_TIME 58 9446
TEST_TIME 59 9354
TEST_TIME 60 9272
TEST_TIME 61 9374
TEST_TIME 62 9380
TEST_TIME 63 9364
TEST_TIME 64 9363
TEST_TIME 65 9337
TEST_TIME 66 9394
TEST_TIME 67 9358
TEST_TIME 68 9320
TEST_TIME 69 9569
TEST_TIME 70 9579
TEST_TIME 71 9653
TEST_TIME 72 9602
TEST_TIME 73 9552
TEST_TIME 74 9544
TEST_TIME 75 9499
TEST_TIME 76 9458
TEST_TIME 77 9411
TEST_TIME 78 9357
TEST_TIME 79 9347
TEST_TIME 80 9346
TEST_TIME 81 9321
TEST_TIME 82 9245
TEST_TIME 83 9241
TEST_TIME 84 9228
TEST_TIME 85 9201
TEST_TIME 86 9149
TEST_TIME 87 9188
TEST_TIME 88 9169
TEST_TIME 89 9170
TEST_TIME 90 9162
TEST_TIME 91 9135
TEST_TIME 92 9226
TEST_TIME 93 9220
TEST_TIME 94 9242
TEST_TIME 95 9268
TEST_TIME 96 9238
TEST_TIME 97 9256
TEST_TIME 98 9178
TEST_TIME 99 9162
TEST_TIME 100 9108
TEST_TIME 101 9118
TEST_TIME 102 9082
TEST_TIME 103 9052
TEST_TIME 104 9002
TEST_TIME 105 8951
TEST_TIME 106 8929
TEST_TIME 107 8939
TEST_TIME 108 8975
TEST_TIME 109 8947
TEST_TIME 110 8946
TEST_TIME 111 8938
TEST_TIME 112 8948
TEST_TIME 113 9003
TEST_TIME 114 9000
TEST_TIME 115 8969
TEST_TIME 116 8993
TEST_TIME 117 9090
TEST_TIME 118 9073
TEST_TIME 119 9012
TEST_TIME 120 8937

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-05-2022 10:39:57 to 18-05-2022 10:40:00.

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 1487486 1487486 0 0.00 0 26324 15 0 0
doc_list_biud 1487472 1487472 0 0.00 0 45466 45 0 0
sp_add_invoice_to_stock 5044 4959 85 1.68 22 260582 27969 0 85
sp_cancel_adding_invoice 5412 5412 0 0.00 7 97511 1331 0 0
sp_cancel_client_order 1698 1698 0 0.00 7 10976 456 0 0
sp_cancel_customer_reserve 528955 528955 0 0.00 1 30360 109 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 3 6577 150 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 11289 157 0 0
sp_cancel_supplier_invoice 5993 5982 11 0.18 3 45477 1151 0 11
sp_cancel_supplier_order 435 435 0 0.00 19 1647310 171113 0 0
sp_cancel_write_off 9641 9641 0 0.00 2 23702 184 0 0
sp_client_order 20563 20563 0 0.00 6 31983 482 0 0
sp_customer_reserve 454904 454904 0 0.00 1 96429 270 0 0
sp_fill_shopping_cart 31517 31517 0 0.00 1 8797 79 0 0
sp_fill_shopping_cart_clo_res 454869 454869 0 0.00 0 23405 21 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 26929 296 0 0
sp_kill_cost_storno 16220 16220 0 0.00 0 7946 86 0 0
sp_kill_qstorno_ret_qs2qd 1073968 1073968 0 0.00 0 45464 48 0 0
sp_kill_qty_storno 567059 567059 0 0.00 0 45465 109 0 0
sp_lock_selected_doc 20646 20646 0 0.00 0 9703 8 0 0
sp_make_cost_storno 27761 27761 0 0.00 0 26311 84 0 0
sp_multiply_rows_for_pdistr 27758 27758 0 0.00 0 7165 40 0 0
sp_multiply_rows_for_qdistr 20563 20563 0 0.00 0 22057 174 0 0
sp_pay_from_customer 7043 7043 0 0.00 5 16673 194 0 0
sp_pay_to_supplier 5781 5781 0 0.00 7 14016 202 0 0
sp_payment_common 12821 12821 0 0.00 2 7197 47 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 1 10969 379 0 0
sp_qd_handle_on_invoice_upd_sts 10315 10315 0 0.00 0 18225 555 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 23049 70 0 0
sp_reserve_write_off 10034 10034 0 0.00 8 23144 311 0 0
sp_supplier_invoice 5460 5460 0 0.00 8 81771 3194 0 0
sp_supplier_order 5505 5505 0 0.00 9 33675 1445 0 0
srv_aggregate_perf_data 106 106 0 0.00 3078 153763 16616 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 913 161844 34766 0 0
srv_find_qd_qs_mism 1020289 1020289 0 0.00 0 19758 12 0 0
srv_make_invnt_saldo 519 519 0 0.00 69 76447 3893 0 0
srv_make_money_saldo 870 870 0 0.00 1 8401 393 0 0
srv_recalc_idx_stat 4 4 0 0.00 43465 398538 230960 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4963 4963 0 0.00 0 25764 1023 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 7499 548 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3149 33 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 914 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 2241 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1359 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 2382 43 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 105097 7385 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1264 18 0 0
t$perf-norm:sp_client_order 20563 20563 0 0.00 0 23953 44 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 22355 52 0 0
t$perf-norm:sp_pay_from_customer 7043 7043 0 0.00 0 11016 21 0 0
t$perf-norm:sp_pay_to_supplier 5781 5781 0 0.00 0 21986 22 0 0
t$perf-norm:sp_reserve_write_off 10035 10035 0 0.00 0 24681 26 0 0
t$perf-norm:sp_supplier_invoice 5460 5460 0 0.00 0 3040 35 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 3489 35 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 375 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 941 23 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 2400 26 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 344 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 206 96 0 0
v_add_invoice_to_stock 9972 9802 170 1.70 0 23531 34 0 170
v_all_customers 37628 37628 0 0.00 0 162 4 0 0
v_all_suppliers 30542 30542 0 0.00 0 69 4 0 0
v_all_wares 41122 41122 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1586 16 0 0
v_cancel_client_order 3396 3396 0 0.00 0 4488 12 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 2637 14 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 1 14276 22 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 2939 19 0 0
v_cancel_supplier_invoice 750 728 22 2.93 0 12749 50 0 22
v_cancel_supplier_order 870 870 0 0.00 0 6179 26 0 0
v_cancel_write_off 1950 1950 0 0.00 0 23564 37 0 0
v_max_id_clo_ord 5498 5498 0 0.00 0 816 5 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 3619 13 0 0
v_max_id_ord_sup 5456 5456 0 0.00 0 3817 7 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 713 8 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 2873 8 0 0
v_min_id_clo_ord 5498 5498 0 0.00 0 2705 8 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 4443 15 0 0
v_min_id_ord_sup 5456 5456 0 0.00 0 3037 8 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 5605 16 0 0
v_min_non_paid_realizn 7042 7042 0 0.00 0 5937 18 0 0
v_our_firm 10176 10176 0 0.00 0 53 5 0 0
v_reserve_write_off 20064 20064 0 0.00 0 21282 20 0 0
x_lock_depdocs_on_canc_invoice 11030 11030 0 0.00 0 12711 121 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 7689 378 0 0
x_make_qstorno_1000_1200 5505 5505 0 0.00 2 33669 1271 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 4 81759 2989 0 0
x_make_qstorno_2100_3300 454903 454903 0 0.00 0 96427 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-05-2022 10:40:01 to 18-05-2022 10:40:05.

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
08:07:21 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 44551 8192000000 9732997792 10033623040 801868528 8.915 100 85 170 0 830817424 7881072 21067824
08:10:11 14643 8192000000 9758927232 10067025920 832804928 9.227 100 89 178 0 867413408 13377568 21230912
08:11:34 16229 8192000000 9804389904 10125209600 868626768 9.586 100 82 164 2 910441376 22806464 19008144
08:12:56 12322 8192000000 9874831184 10211889152 925898016 10.154 100 79 158 1 983916272 40789408 17228848
08:14:12 5370 8192000000 9908783616 10256240640 958238816 10.472 100 79 155 1 1033972432 59317968 16415648
08:15:22 2847 8192000000 9965103936 10319323136 993521424 10.816 100 90 177 1 1080558688 68289152 18748112
08:16:27 2830 8192000000 9998469264 10363416576 1019494080 11.067 100 83 165 0 1123628528 88063968 16070480
08:17:32 1086 8192000000 10019116064 10387279872 1040527136 11.270 100 96 184 1 1135710240 76410032 18773072
08:18:35 2895 8192000000 10021206720 10398236672 1054209440 11.401 100 86 165 0 1143886976 73971536 15706000
08:19:41 812 8192000000 10042724672 10418536448 1063090480 11.486 100 96 192 2 1162631968 80229808 19311680
08:20:44 6772 8192000000 10036504896 10423349248 1069427680 11.547 100 92 177 0 1160288720 73403808 17457232
08:21:53 1294 8192000000 10047254368 10429673472 1079637680 11.644 100 96 182 2 1169010944 71183424 18189840
08:22:56 1118 8192000000 10053647584 10436665344 1081481312 11.662 100 97 190 2 1173439104 73621152 18336640
08:23:58 1274 8192000000 10064954960 10451369984 1088720256 11.730 100 94 180 1 1184311728 77140528 18450944
08:25:01 1294 8192000000 10071769696 10458910720 1090245136 11.745 100 99 192 0 1195119264 83990368 20883760
08:26:03 4066 8192000000 10036375472 10423472128 1092994672 11.771 100 86 163 6 1150176096 42393008 14788416
08:27:09 1200 8192000000 10096881728 10485514240 1096899968 11.808 100 90 164 0 1218261440 104111264 17250208
08:28:11 1084 8192000000 10055727840 10443108352 1084785856 11.693 100 91 176 0 1175416064 72644400 17985808
08:29:13 1085 8192000000 10073151760 10464415744 1089701344 11.740 100 90 175 1 1197117744 88841312 18575088
08:30:16 881 8192000000 10033819056 10421833728 1080479776 11.652 100 87 168 2 1151595392 53436480 17679136
08:31:18 6102 8192000000 10026463072 10416107520 1086085936 11.705 100 77 144 2 1153827232 52652448 15088848
08:32:27 1007 8192000000 10036853648 10421202944 1070094176 11.553 100 91 168 1 1158975568 72581472 16299920
08:33:29 1093 8192000000 9990390176 10358714368 1032672288 11.194 98 95 182 2 1116538192 64301776 19564128
08:34:31 1061 8192000000 10003723296 10376691712 1042056448 11.284 100 84 157 0 1124827216 65613536 17157232
08:35:33 1458 8192000000 10008073504 10378256384 1041348352 11.278 100 95 173 0 1129191504 72257792 15585360
08:36:35 9251 8192000000 9995819408 10372554752 1036271616 11.229 99 66 125 2 1106191776 58531408 11388752
08:37:49 1112 8192000000 10027599680 10404507648 1062925632 11.484 100 92 169 0 1142032560 61922592 17184336
08:39:02 2108 8192000000 10029219008 10412519424 1073593488 11.586 100 78 145 0 1147758528 59712720 14452320
08:40:10 2671 8192000000 10048662384 10426949632 1081642400 11.663 100 89 159 3 1165690736 65852576 18195760
08:41:41 3010 8192000000 10073246576 10461261824 1100157776 11.839 100 74 141 1 1195834352 80950160 14726416
08:42:48 1232 8192000000 10114394144 10498293760 1110588960 11.938 100 95 181 1 1236137584 105103552 20445072
08:43:53 5661 8192000000 10126056240 10522648576 1114127328 11.971 100 67 132 1 1244238208 116420016 13690864
08:45:01 2515 8192000000 10156144464 10549215232 1132519984 12.145 100 84 160 0 1269923456 119725536 17677936
08:46:12 4016 8192000000 10193779760 10592079872 1139483504 12.211 100 71 133 2 1308754176 155387568 13883104
08:47:27 7634 8192000000 10256340528 10652901376 1153633984 12.344 100 79 155 1 1376998176 206739696 16624496
08:48:41 1105 8192000000 10294611616 10694533120 1167814240 12.476 100 99 196 0 1404629968 214781824 22033904
08:49:44 3169 8192000000 10318835536 10732916736 1175670288 12.550 100 75 147 0 1428170352 236107984 16392080
08:50:55 891 8192000000 10346171248 10745651200 1176173136 12.554 100 95 181 1 1454644800 257152816 21318848
08:51:59 1369 8192000000 10394518000 10799828992 1190900672 12.692 100 86 170 1 1501070800 290425600 19744528
08:53:05 1099 8192000000 10446794736 10851594240 1195805328 12.737 100 88 173 0 1553185312 337453760 19926224
08:54:13 1783 8192000000 10448998416 10869821440 1207457344 12.846 100 93 178 0 1560067280 329862896 22747040
08:55:20 1326 8192000000 10505266864 10923397120 1212175824 12.889 100 97 188 1 1618031696 383137440 22718432
08:56:26 1782 8192000000 10526736640 10941706240 1203707264 12.811 100 92 184 1 1633987104 407785456 22494384
08:57:35 2180 8192000000 10544518896 11004014592 1214216272 12.908 100 98 189 0 1648335360 411730096 22388992
08:58:42 1093 8192000000 10583510464 11028353024 1209571072 12.865 100 95 187 1 1693309632 461068352 22670208
08:59:45 1491 8192000000 10649143456 11057823744 1208939248 12.859 100 93 182 0 1753636912 522857648 21840016
09:00:49 1189 8192000000 10667336704 11099869184 1202203200 12.797 100 92 185 0 1768537616 543763552 22570864
09:01:57 1689 8192000000 10670684080 11122782208 1194841216 12.728 100 89 176 1 1777008944 560584400 21583328
09:03:02 2274 8192000000 10677781024 11137138688 1205392400 12.826 100 77 152 0 1780927200 556232800 19302000
09:04:07 1301 8192000000 10676978272 11131518976 1205299904 12.826 100 85 165 0 1778497760 552597280 20600576
09:05:11 7041 8192000000 10626463840 11138985984 1202635184 12.801 100 62 124 0 1743959152 524362848 16961120
09:06:22 15095 8192000000 10494980448 11015507968 1184537920 12.632 100 49 94 0 1617295744 420112224 12645600
09:07:43 9696 8192000000 10427954496 10937368576 1165424224 12.454 99 34 67 0 1519960656 345540800 8995632
09:09:04 45833 8192000000 10281912480 10766602240 1143521328 12.249 98 24 48 0 1384340256 234969424 5849504
09:11:01 55432 8192000000 10139750592 10639237120 1123525824 12.060 97 16 32 0 1242721104 115443984 3751296
09:13:23 16345 8192000000 10063707040 10482446336 1092837232 11.770 96 47 73 4 1119065792 19964784 6263776
09:14:41 20345 8192000000 10142260112 10579562496 1147369696 12.285 100 39 80 1 1239161664 83399056 8392912
09:16:19 42624 8192000000 10161540112 10595180544 1123617552 12.061 99 54 96 4 1261258224 127698640 9942032
09:18:22 129991 8192000000 10083795776 10510938112 1113660288 11.967 99 32 63 0 1186299472 65892608 6746576
09:21:47 78301 8192000000 10096296720 10540097536 1125382096 12.078 99 38 71 0 1164912848 31895840 7634912
09:24:13 20182 8192000000 10110831936 10549456896 1128758512 12.110 99 30 60 2 1195537392 59615168 7163712
09:25:42 28791 8192000000 10135411488 10562633728 1117202288 12.001 99 43 83 2 1222180816 95897904 9080624
09:27:14 161843 8192000000 10059962880 10502631424 1093918880 11.780 96 28 56 1 1166378960 66340368 6119712
09:32:13 79714 8192000000 10088993120 10551226368 1119058944 12.018 99 11 20 1 1161599792 40508368 2032480
09:35:10 19305 8192000000 10066493504 10555166720 1119335248 12.021 100 16 32 1 1174126128 51519968 3270912
09:36:31 114234 8192000000 10113842992 10610249728 1118096880 12.009 99 18 36 0 1212583264 90465920 4020464
09:39:32 31236 8192000000 10057006960 10503999488 1117016864 11.999 99 35 69 0 1163873888 39941664 6915360
09:41:06 25792 8192000000 10102891440 10542755840 1122812912 12.054 99 32 60 0 1177922624 48014080 7095632
09:42:33 42065 8192000000 10126108816 10593320960 1110017760 11.933 98 31 61 1 1228962640 112186800 6758080
09:44:27 51147 8192000000 10195066368 10635853824 1136855264 12.186 100 24 47 1 1289512448 147258000 5399184
09:46:19 53013 8192000000 10106278736 10546053120 1141916032 12.234 100 22 45 0 1229125840 82511728 4698080
09:48:14 89026 8192000000 10109754528 10538614784 1116409264 11.993 97 24 46 2 1148844592 27975184 4460144
09:50:44 31086 8192000000 10145643072 10582200320 1120695808 12.034 98 62 125 1 1230962336 96701600 13564928
09:52:18 58249 8192000000 10090988208 10548224000 1136616400 12.184 100 42 82 3 1194261344 48914000 8730944
09:54:26 18724 8192000000 10119142480 10562121728 1142511152 12.239 100 44 87 1 1216080416 64868960 8700304
09:55:52 92526 8192000000 10094994176 10604351488 1150871648 12.318 100 14 28 1 1221183520 67062512 3249360
09:58:25 95070 8192000000 10094798864 10559311872 1132055088 12.141 98 9 17 0 1174090240 40536864 1498288
10:01:00 59254 8192000000 10064709104 10538393600 1089442656 11.737 96 13 27 1 1148349760 55744560 3162544
10:03:25 29450 8192000000 10104616992 10540134400 1115509552 11.985 99 33 66 0 1181004368 58568848 6925968
10:04:55 65718 8192000000 10083927040 10522406912 1080963824 11.657 96 19 38 1 1126088384 41306848 3817712
10:07:05 31038 8192000000 10122438480 10565726208 1091847264 11.760 97 36 72 1 1184243904 85266128 7130512
10:08:44 26264 8192000000 10103989888 10569277440 1126769184 12.091 100 26 52 1 1210134160 77920720 5444256
10:10:10 13750 8192000000 10097452672 10526404608 1110748128 11.940 98 59 116 0 1161764352 39725104 11291120
10:11:25 22101 8192000000 10110228928 10555772928 1123662832 12.062 99 36 72 2 1209688992 78930560 7095600
10:12:54 35468 8192000000 10101968336 10531442688 1130106720 12.122 100 44 86 0 1196803248 58108832 8587696
10:14:44 106196 8192000000 10078778560 10537021440 1094040320 11.781 97 50 96 1 1183042704 76506512 12495872
10:17:41 51813 8192000000 10051566464 10521554944 1115847296 11.988 99 18 36 1 1180028352 60491728 3689328
10:19:38 56252 8192000000 10031510608 10491568128 1099309424 11.831 98 17 35 0 1137146832 33589264 4248144
10:21:37 82649 8192000000 10115483280 10570162176 1123547984 12.060 100 18 34 0 1191777904 64327536 3902384
10:24:00 84689 8192000000 10053928336 10542862336 1111099712 11.943 99 13 26 1 1190911440 76757680 3054048
10:26:26 35081 8192000000 10122762160 10558201856 1136963312 12.187 100 49 98 1 1204200144 57181264 10055568
10:28:02 31094 8192000000 10135427264 10584428544 1113405680 11.965 98 24 48 1 1196890528 78438480 5046368
10:29:42 20649 8192000000 10132999344 10567708672 1136588576 12.183 100 33 64 1 1228459664 85077392 6793696
10:31:03 35157 8192000000 10118936128 10563067904 1113529376 11.966 97 32 62 1 1173088000 52955200 6603424
10:32:39 27983 8192000000 10191981856 10622525440 1149623776 12.306 100 52 102 1 1276948480 116481120 10843584
10:34:12 76521 8192000000 10074075584 10568261632 1122170640 12.047 98 9 18 0 1192352576 68381824 1800112
10:36:31 90555 8192000000 9211617776 9523363840 346378704 4.056 31 13 21 3 395693408 46750352 2564352

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 10:40:07 to 18-05-2022 10:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-05-2022 10:40:24 to 18-05-2022 10:40:26.

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          220467
MON$OLDEST_ACTIVE               220468
MON$OLDEST_SNAPSHOT             220468
MON$NEXT_TRANSACTION            220468
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                       2054688
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             771
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 ---
:311: 
:312: mscs-test-161.budzdorov.ru    Wed May 18 10:38:14 2022
:313:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:314: 
:315: 
:316: mscs-test-161.budzdorov.ru    Wed May 18 10:38:20 2022
:317:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:318: 
:319: 
:320: mscs-test-161.budzdorov.ru    Wed May 18 10:38:25 2022
:321:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:322: 
--- end of diff output ---

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

18.05.22 10:40:27. 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 10:40:30 No severe PSQL-related problems occured
18.05.22 10:40:31. End of report.