Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2022 10: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 15218724 kB
MemAvailable 30451876 kB
Buffers 0 kB
Cached 16461388 kB
SwapCached 2072 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-19 10:38:02
DTS_END                         2022-10-19 10:37:23.2800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42742
TRN_ID                          210813
ATT_ID                          259
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: 8603.05 26488 1032366
customer order: creation 156.59 545 18791
customer order: refuse 13.15 431 1578
order to supplier: creation 41.15 1454 4938
order to supplier: removal 3.09 173171 371
invoice (draft): creation 42.45 3313 5094
invoice (draft): removal 48.88 956 5866
invoice accept: apply 38.35 25663 4602
invoice accept: cancel 44.03 1147 5284
customer reserve: creation 3636.43 238 436371
customer reserve: removal 4312.43 98 517491
realization accept: apply 76.13 311 9136
realization accept: cancel 72.51 155 8701
payment from customer: creation 54.45 191 6534
payment from customer: removal 4.95 122 594
payment to supplier: creation 43.91 197 5269
payment to supplier: removal 3.13 147 375
service: total inventory turnovers 3.32 4716 398
service: total monetary turnovers 7.11 412 853
service: aggregate perf. data 0.96 15233 115
service: refresh index statistics 0.04 301264 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-10-2022 10:38:32 to 19-10-2022 10:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4475
TEST_TIME 2 4179
TEST_TIME 3 4819
TEST_TIME 4 5676
TEST_TIME 5 5644
TEST_TIME 6 5581
TEST_TIME 7 5938
TEST_TIME 8 6105
TEST_TIME 9 6003
TEST_TIME 10 6326
TEST_TIME 11 6823
TEST_TIME 12 6938
TEST_TIME 13 6897
TEST_TIME 14 6920
TEST_TIME 15 7065
TEST_TIME 16 7290
TEST_TIME 17 7659
TEST_TIME 18 7914
TEST_TIME 19 7872
TEST_TIME 20 7863
TEST_TIME 21 8102
TEST_TIME 22 8438
TEST_TIME 23 8573
TEST_TIME 24 8656
TEST_TIME 25 8696
TEST_TIME 26 9315
TEST_TIME 27 9560
TEST_TIME 28 9652
TEST_TIME 29 10210
TEST_TIME 30 10288
TEST_TIME 31 11072
TEST_TIME 32 11068
TEST_TIME 33 10819
TEST_TIME 34 10519
TEST_TIME 35 10410
TEST_TIME 36 10264
TEST_TIME 37 10366
TEST_TIME 38 10384
TEST_TIME 39 10390
TEST_TIME 40 10356
TEST_TIME 41 10193
TEST_TIME 42 10195
TEST_TIME 43 10051
TEST_TIME 44 9927
TEST_TIME 45 9864
TEST_TIME 46 9690
TEST_TIME 47 9849
TEST_TIME 48 9784
TEST_TIME 49 9787
TEST_TIME 50 9664
TEST_TIME 51 9543
TEST_TIME 52 9465
TEST_TIME 53 9446
TEST_TIME 54 9433
TEST_TIME 55 9438
TEST_TIME 56 9399
TEST_TIME 57 9314
TEST_TIME 58 9198
TEST_TIME 59 9086
TEST_TIME 60 9129
TEST_TIME 61 9030
TEST_TIME 62 9026
TEST_TIME 63 8920
TEST_TIME 64 8822
TEST_TIME 65 8801
TEST_TIME 66 8865
TEST_TIME 67 8808
TEST_TIME 68 8885
TEST_TIME 69 8899
TEST_TIME 70 8875
TEST_TIME 71 8901
TEST_TIME 72 8917
TEST_TIME 73 8921
TEST_TIME 74 8952
TEST_TIME 75 9001
TEST_TIME 76 8969
TEST_TIME 77 8977
TEST_TIME 78 9044
TEST_TIME 79 8953
TEST_TIME 80 8869
TEST_TIME 81 8837
TEST_TIME 82 8786
TEST_TIME 83 8761
TEST_TIME 84 8721
TEST_TIME 85 8697
TEST_TIME 86 8701
TEST_TIME 87 8660
TEST_TIME 88 8624
TEST_TIME 89 8629
TEST_TIME 90 8625
TEST_TIME 91 8668
TEST_TIME 92 8649
TEST_TIME 93 8677
TEST_TIME 94 8659
TEST_TIME 95 8829
TEST_TIME 96 8827
TEST_TIME 97 8899
TEST_TIME 98 8920
TEST_TIME 99 8987
TEST_TIME 100 8927
TEST_TIME 101 8849
TEST_TIME 102 8833
TEST_TIME 103 8788
TEST_TIME 104 8738
TEST_TIME 105 8727
TEST_TIME 106 8710
TEST_TIME 107 8661
TEST_TIME 108 8641
TEST_TIME 109 8606
TEST_TIME 110 8575
TEST_TIME 111 8532
TEST_TIME 112 8607
TEST_TIME 113 8610
TEST_TIME 114 8605
TEST_TIME 115 8618
TEST_TIME 116 8605
TEST_TIME 117 8677
TEST_TIME 118 8662
TEST_TIME 119 8679
TEST_TIME 120 8617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2022 10:38:36 to 19-10-2022 10:38:39.

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 1432904 1432904 0 0.00 0 18217 13 0 0
doc_list_biud 1432888 1432888 0 0.00 0 33883 41 0 0
sp_add_invoice_to_stock 4728 4602 126 2.66 48 187633 25664 0 126
sp_cancel_adding_invoice 5284 5284 0 0.00 7 81864 1148 0 0
sp_cancel_client_order 1578 1578 0 0.00 6 19193 431 0 0
sp_cancel_customer_reserve 517491 517491 0 0.00 1 32932 98 0 0
sp_cancel_pay_from_customer 594 594 0 0.00 4 2321 123 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 3672 148 0 0
sp_cancel_supplier_invoice 5875 5866 9 0.15 3 31963 957 0 9
sp_cancel_supplier_order 371 371 0 0.00 13 1333581 173171 0 0
sp_cancel_write_off 8701 8701 0 0.00 2 28961 156 0 0
sp_client_order 18791 18791 0 0.00 6 34555 545 0 0
sp_customer_reserve 436371 436371 0 0.00 1 76354 238 0 0
sp_fill_shopping_cart 28814 28814 0 0.00 2 32957 92 0 0
sp_fill_shopping_cart_clo_res 436357 436357 0 0.00 0 14648 21 0 0
sp_get_clo_for_invoice 4586 4586 0 0.00 0 37246 401 0 0
sp_kill_cost_storno 14954 14954 0 0.00 0 14761 69 0 0
sp_kill_qstorno_ret_qs2qd 1049920 1049920 0 0.00 0 32757 43 0 0
sp_kill_qty_storno 553004 553004 0 0.00 0 33877 99 0 0
sp_lock_selected_doc 18995 18995 0 0.00 0 6491 6 0 0
sp_make_cost_storno 25525 25525 0 0.00 0 18213 85 0 0
sp_multiply_rows_for_pdistr 25519 25519 0 0.00 0 9356 39 0 0
sp_multiply_rows_for_qdistr 18791 18791 0 0.00 0 14040 202 0 0
sp_pay_from_customer 6534 6534 0 0.00 5 16879 192 0 0
sp_pay_to_supplier 5269 5269 0 0.00 6 12630 198 0 0
sp_payment_common 11799 11799 0 0.00 2 16825 45 0 0
sp_qd_handle_on_cancel_clo 1578 1578 0 0.00 1 19117 356 0 0
sp_qd_handle_on_invoice_upd_sts 9869 9869 0 0.00 0 28003 528 0 0
sp_qd_handle_on_reserve_upd_sts 9128 9128 0 0.00 0 33871 90 0 0
sp_reserve_write_off 9136 9136 0 0.00 8 34151 312 0 0
sp_supplier_invoice 5094 5094 0 0.00 11 89292 3314 0 0
sp_supplier_order 4938 4938 0 0.00 7 49758 1454 0 0
srv_aggregate_perf_data 115 115 0 0.00 2240 167674 15233 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 939 145031 38330 0 0
srv_find_qd_qs_mism 986570 986570 0 0.00 0 16620 9 0 0
srv_make_invnt_saldo 398 398 0 0.00 60 119946 4717 0 0
srv_make_money_saldo 853 853 0 0.00 1 33542 412 0 0
srv_recalc_idx_stat 5 5 0 0.00 88109 580587 301265 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4608 4608 0 0.00 1 12754 970 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 2 11258 492 0 0
t$perf-norm:sp_cancel_client_order 1578 1578 0 0.00 0 5181 33 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 1071 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 594 594 0 0.00 0 1166 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 1193 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2938 39 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 121041 7236 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 6228 36 0 0
t$perf-norm:sp_client_order 18792 18792 0 0.00 0 9108 38 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 1621 18 0 0
t$perf-norm:sp_pay_from_customer 6534 6534 0 0.00 0 5018 22 0 0
t$perf-norm:sp_pay_to_supplier 5271 5271 0 0.00 0 4936 19 0 0
t$perf-norm:sp_reserve_write_off 9136 9136 0 0.00 0 8716 25 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 4070 29 0 0
t$perf-norm:sp_supplier_order 4938 4938 0 0.00 0 8696 35 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 417 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1651 67 0 0
t$perf-norm:srv_make_invnt_saldo 398 398 0 0.00 0 3750 24 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 1478 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 296 107 0 0
v_add_invoice_to_stock 9417 9165 252 2.67 0 6699 29 0 252
v_all_customers 34690 34690 0 0.00 0 71 4 0 0
v_all_suppliers 27596 27596 0 0.00 0 69 4 0 0
v_all_wares 37566 37566 0 0.00 0 53 4 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 5943 24 0 0
v_cancel_client_order 3156 3156 0 0.00 0 5533 14 0 0
v_cancel_customer_prepayment 1188 1188 0 0.00 0 1024 11 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 1 2064 12 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 1427 12 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 12501 43 0 18
v_cancel_supplier_order 742 742 0 0.00 0 2454 19 0 0
v_cancel_write_off 1860 1860 0 0.00 0 5914 27 0 0
v_max_id_clo_ord 4935 4935 0 0.00 0 288 5 0 0
v_max_id_clo_res 918 918 0 0.00 0 1035 12 0 0
v_max_id_ord_sup 5089 5089 0 0.00 0 4899 8 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 2961 9 0 0
v_max_non_paid_realizn 3215 3215 0 0.00 0 504 7 0 0
v_min_id_clo_ord 4935 4935 0 0.00 0 782 7 0 0
v_min_id_clo_res 918 918 0 0.00 0 6392 21 0 0
v_min_id_ord_sup 5089 5089 0 0.00 0 4957 11 0 0
v_min_non_paid_invoice 5267 5267 0 0.00 0 3040 14 0 0
v_min_non_paid_realizn 6532 6532 0 0.00 0 6051 17 0 0
v_our_firm 9510 9510 0 0.00 0 68 5 0 0
v_reserve_write_off 18254 18254 0 0.00 0 5921 13 0 0
x_lock_depdocs_on_canc_invoice 10737 10737 0 0.00 0 16802 131 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 9659 394 0 0
x_make_qstorno_1000_1200 4938 4938 0 0.00 2 49589 1289 0 0
x_make_qstorno_1200_2000 5094 5094 0 0.00 4 67066 3041 0 0
x_make_qstorno_2100_3300 436371 436371 0 0.00 0 76348 205 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-10-2022 10:38:40 to 19-10-2022 10:38:45.

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:27 2 8192000000 8797495360 8808288256 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 30943 8192000000 9727161040 10032607232 803904576 8.936 100 83 167 0 831681072 7550048 20226448
08:10:06 14547 8192000000 9769206896 10081280000 844815136 9.348 100 89 178 0 879658976 13494480 21349360
08:11:30 5443 8192000000 9799426240 10121637888 869408832 9.594 100 85 168 2 913330624 24758000 19163792
08:12:42 4861 8192000000 9869943920 10200485888 916537840 10.062 100 81 160 0 983482400 49345504 17599056
08:13:52 2243 8192000000 9920518352 10260615168 946904736 10.361 100 94 185 1 1041171232 74993104 19273392
08:14:57 1676 8192000000 9952560896 10304090112 983442688 10.718 100 92 176 1 1078867648 77439824 17985136
08:16:01 1499 8192000000 9997153696 10351812608 1009736336 10.973 100 90 173 0 1110292848 83674096 16882416
08:17:05 1185 8192000000 10001914800 10370416640 1041988176 11.284 100 94 185 3 1128063728 68126912 17948640
08:18:08 1211 8192000000 10032352960 10400534528 1055501728 11.413 100 96 185 2 1153281120 78040304 19739088
08:19:10 792 8192000000 10026541936 10401513472 1063335872 11.488 100 94 185 0 1149562704 68000432 18226400
08:20:13 1144 8192000000 10050096000 10430259200 1070572736 11.558 100 99 191 1 1170525264 80446768 19505760
08:21:15 916 8192000000 10042704592 10423578624 1082067232 11.667 100 91 172 0 1168203920 67597824 18538864
08:22:18 1201 8192000000 10068650976 10456162304 1087609712 11.720 100 98 186 2 1189238960 82863856 18765392
08:23:22 1185 8192000000 10057502096 10446749696 1091056784 11.753 100 99 188 1 1182428048 72451392 18919872
08:24:24 932 8192000000 10060438384 10452631552 1095045248 11.791 100 92 174 0 1182104976 68703248 18356480
08:25:26 1326 8192000000 10058405216 10445230080 1091889536 11.761 100 95 187 1 1173336720 61414784 20032400
08:26:29 1543 8192000000 10059264416 10447429632 1095417936 11.794 100 75 140 1 1181588240 71055536 15114768
08:27:32 1067 8192000000 10052424608 10444177408 1094768496 11.788 100 89 171 1 1170456752 57927920 17760336
08:28:35 2159 8192000000 10025267408 10414862336 1073659376 11.587 99 69 138 3 1147399216 59823520 13916320
08:29:40 1074 8192000000 10040031584 10421256192 1064323248 11.498 100 94 178 2 1159669152 76417744 18928160
08:30:42 1366 8192000000 10044450608 10424172544 1064083648 11.496 100 97 186 2 1166182608 83531904 18567056
08:31:45 2911 8192000000 10043970288 10430029824 1071812160 11.569 100 74 138 1 1171383024 85451760 14119104
08:32:49 999 8192000000 9983235232 10354900992 1034155056 11.208 99 90 174 1 1104783952 52652512 17976384
08:33:50 1818 8192000000 9972479184 10351869952 1043761184 11.301 99 72 139 3 1098978848 40308048 14909616
08:34:54 1986 8192000000 10002285824 10376376320 1046070496 11.323 100 78 139 3 1120895152 61959600 12865056
08:35:58 1111 8192000000 10003327456 10374713344 1034961216 11.216 100 95 181 0 1124864176 70869632 19033328
08:37:00 1155 8192000000 10002408704 10378223616 1046590352 11.328 100 94 168 1 1124905648 59052928 19262368
08:38:05 1037 8192000000 9997746816 10377621504 1072078304 11.572 100 84 144 1 1116292096 30098624 14115168
08:39:12 22123 8192000000 10066332304 10456264704 1096108048 11.801 100 70 135 0 1185939712 75807008 14024656
08:40:37 937 8192000000 10088323776 10475433984 1113735584 11.968 100 89 165 1 1204453648 73358512 17359552
08:41:41 1318 8192000000 10135428000 10530439168 1125762960 12.081 100 86 159 1 1253796352 111794112 16239280
08:42:45 2068 8192000000 10165688992 10558717952 1129970912 12.121 100 80 150 0 1285014544 138811360 16232272
08:43:50 10467 8192000000 10170094064 10585423872 1134072016 12.160 100 60 117 0 1284386928 137726592 12588320
08:45:06 1021 8192000000 10215956272 10609299456 1146098464 12.273 100 88 168 1 1326954832 163503360 17353008
08:46:20 1629 8192000000 10230233472 10626785280 1155061968 12.357 100 87 163 0 1343008672 169854720 18091984
08:47:25 1269 8192000000 10283857328 10693140480 1167599680 12.474 100 92 176 2 1397960336 209240032 21120624
08:48:31 3030 8192000000 10279033888 10689937408 1175776736 12.551 100 82 153 0 1384007552 192019856 16210960
08:49:36 2862 8192000000 10304825648 10710917120 1173250592 12.527 99 81 160 1 1416010576 224401952 18358032
08:50:45 1861 8192000000 10380787216 10784755712 1194669568 12.727 100 91 182 1 1491617632 275534864 21413200
08:51:50 3067 8192000000 10404508832 10828959744 1200353008 12.780 100 86 159 0 1514003328 295569952 18080368
08:52:55 1268 8192000000 10399786016 10843217920 1202850784 12.803 100 90 171 1 1509643600 287297120 19495696
08:53:58 1201 8192000000 10470920112 10896482304 1203377648 12.808 100 92 180 1 1570530448 346817440 20335360
08:55:01 2039 8192000000 10486169184 10913271808 1200245664 12.779 100 90 180 1 1592178208 370925520 21007024
08:56:06 1020 8192000000 10487657056 10898116608 1187710960 12.662 100 94 187 0 1592929312 383051568 22166784
08:57:10 1086 8192000000 10502745072 10941624320 1198115472 12.759 100 97 190 1 1602098912 380371712 23611728
08:58:14 1292 8192000000 10556752672 11001528320 1208983280 12.860 100 92 180 0 1663449120 432425232 22040608
08:59:20 2944 8192000000 10495323152 10989776896 1205883456 12.831 100 80 156 0 1599466464 374550992 19032016
09:00:26 3948 8192000000 10506852112 10951725056 1190976880 12.692 100 67 126 1 1613981040 406910480 16093680
09:01:37 1332 8192000000 10528006656 10965241856 1188032448 12.665 100 64 124 2 1633198304 429624928 15540928
09:02:47 4632 8192000000 10486672624 10956005376 1177225648 12.564 100 55 103 0 1589888512 398418400 14244464
09:04:01 16123 8192000000 10393206992 10854637568 1148550016 12.296 99 38 76 0 1506573472 348377952 9645504
09:05:26 17594 8192000000 10352043424 10836250624 1146736992 12.279 100 46 90 0 1498081536 340074480 11270064
09:07:00 43532 8192000000 10252051408 10735476736 1125474288 12.079 99 28 56 0 1373695312 241484144 6736880
09:08:46 52143 8192000000 10100924288 10607239168 1122120928 12.047 98 13 26 0 1182407648 57750720 2536000
09:10:39 80165 8192000000 10150856144 10577313792 1155091104 12.357 100 59 105 2 1242689840 76062528 11536208
09:13:04 41592 8192000000 10233288560 10688045056 1164317872 12.444 100 43 83 2 1325134368 151758432 9058064
09:14:48 41266 8192000000 10173353952 10701062144 1128188112 12.104 98 11 22 0 1273252272 142614752 2449408
09:16:31 76138 8192000000 10112004544 10626879488 1146604000 12.278 100 13 26 0 1237033552 88122336 2307216
09:18:48 79385 8192000000 10140290944 10641076224 1158045904 12.385 100 25 46 0 1244526016 80521728 5958384
09:21:25 93205 8192000000 10168749296 10624110592 1142476784 12.239 99 28 57 0 1262847376 113996800 6373792
09:24:12 113499 8192000000 10068445968 10554564608 1107893456 11.912 97 14 29 0 1202866704 91608896 3364352
09:27:21 83842 8192000000 10088479376 10563674112 1091396272 11.756 96 22 42 0 1160023520 62875392 5751856
09:29:47 55373 8192000000 10108393728 10579734528 1128090864 12.103 99 32 62 2 1231437600 96514784 6831952
09:31:58 60328 8192000000 10056640880 10553507840 1115557824 11.985 99 18 36 0 1161856336 42554496 3744016
09:34:44 90853 8192000000 10087303968 10546040832 1127874080 12.101 99 17 35 1 1171921264 40416128 3631056
09:37:15 55392 8192000000 10044686832 10492882944 1139671376 12.212 100 24 44 1 1168140992 23512832 4956784
09:39:24 70664 8192000000 10064283808 10530697216 1129453856 12.116 99 13 25 0 1186872704 55255904 2162944
09:41:56 85038 8192000000 10108110336 10605076480 1121496880 12.041 98 12 24 0 1230292768 106170880 2625008
09:44:21 31920 8192000000 10144453504 10589913088 1148628064 12.297 100 41 80 1 1228753568 71701616 8423888
09:45:54 32808 8192000000 10138952112 10585669632 1143194272 12.246 99 24 46 1 1216905936 68507696 5203968
09:47:37 30907 8192000000 10138286704 10603003904 1158982768 12.394 100 28 56 1 1239781088 74862816 5935504
09:49:09 48087 8192000000 10119220976 10560745472 1147128464 12.283 99 25 49 0 1242095072 89396272 5570336
09:51:04 45234 8192000000 10110558384 10563690496 1113702032 11.967 97 40 80 0 1200034992 78191664 8141296
09:52:51 47199 8192000000 10101481552 10533539840 1116796448 11.997 98 37 72 0 1169443248 45819072 6827728
09:54:41 22986 8192000000 10142925008 10594492416 1126753328 12.091 99 44 88 0 1232731472 96580160 9397984
09:56:08 75390 8192000000 10056949072 10487808000 1119540336 12.023 99 34 68 0 1152173936 25454624 7178976
09:59:00 56914 8192000000 10070331040 10517049344 1098298032 11.821 97 8 16 0 1154514560 53626992 2589536
10:00:58 45921 8192000000 10050577712 10549854208 1112141168 11.953 99 6 12 0 1151398656 38161456 1096032
10:03:00 64493 8192000000 10031452192 10491977728 1088404656 11.727 96 15 30 0 1126048368 34585776 3057936
10:05:05 9829 8192000000 10052770416 10478305280 1099007696 11.828 99 53 101 2 1152942048 42985824 10948528
10:06:19 62620 8192000000 10113712480 10551750656 1105098400 11.886 98 21 42 0 1208252656 98359024 4795232
10:08:58 58558 8192000000 10136878784 10582372352 1105050560 11.886 97 28 56 0 1220928208 109736064 6141584
10:11:07 61494 8192000000 10144608432 10643607552 1141648688 12.231 100 22 44 1 1271919456 125489152 4781616
10:13:51 38208 8192000000 10129070864 10585354240 1132728528 12.147 100 14 28 0 1217725952 82190080 2807344
10:15:31 145031 8192000000 10058931984 10538504192 1082831584 11.674 96 16 33 0 1122158416 35981968 3344864
10:20:01 57641 8192000000 10051349280 10504466432 1118391936 12.012 99 18 36 1 1146061008 23501104 4167968
10:22:51 39084 8192000000 10044909920 10532446208 1124952256 12.074 100 7 12 0 1166339808 40309152 1078400
10:24:32 63221 8192000000 10059027984 10520948736 1126638320 12.090 100 29 58 0 1165720864 32853392 6229152
10:26:46 50287 8192000000 10101837840 10533228544 1099522560 11.833 97 34 68 0 1171060784 64006400 7531824
10:28:41 102443 8192000000 10121226960 10653642752 1133233824 12.152 100 12 24 0 1255405440 119576224 2595392
10:31:26 38637 8192000000 10117562880 10568740864 1125509312 12.079 99 29 57 0 1203493568 72067600 5916656
10:33:06 29370 8192000000 10145440576 10583650304 1118001632 12.008 98 32 60 0 1217332528 93119984 6210912
10:34:36 36024 8192000000 10103747824 10548805632 1125284016 12.077 99 34 62 1 1176021984 43452672 7285296
10:36:15 43951 8192000000 10142509600 10580537344 1146348144 12.275 100 27 52 0 1249410288 97614928 5447216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-10-2022 10:38:47 to 19-10-2022 10:39:03.

Exceptions occured during test was in run

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

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

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          215020
MON$OLDEST_ACTIVE               215021
MON$OLDEST_SNAPSHOT             215021
MON$NEXT_TRANSACTION            215021
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                       2061792
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

19.10.22 10:39:07. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-19 10:39:09 No severe PSQL-related problems occured
19.10.22 10:39:11. End of report.