Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2022 01: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 14964812 kB
MemAvailable 30048984 kB
Buffers 0 kB
Cached 16730488 kB
SwapCached 3492 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-12-20 01:38:30
DTS_END                         2022-12-20 01:38:30.6210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46566
TRN_ID                          240720
ATT_ID                          689
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: 9756.90 23485 1170828
customer order: creation 172.96 356 20755
customer order: refuse 15.02 334 1802
order to supplier: creation 45.97 1189 5516
order to supplier: removal 3.68 89114 442
invoice (draft): creation 45.24 3215 5429
invoice (draft): removal 56.28 663 6753
invoice accept: apply 42.52 16109 5102
invoice accept: cancel 51.23 680 6147
customer reserve: creation 3959.73 157 475167
customer reserve: removal 5061.46 51 607375
realization accept: apply 84.46 170 10135
realization accept: cancel 88.14 93 10577
payment from customer: creation 60.13 128 7215
payment from customer: removal 6.03 104 724
payment to supplier: creation 48.42 137 5810
payment to supplier: removal 3.65 88 438
service: total inventory turnovers 4.52 1062 542
service: total monetary turnovers 6.59 39 791
service: aggregate perf. data 0.87 18379 104
service: refresh index statistics 0.03 337627 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2022 01:39:45 to 20-12-2022 01:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3642
TEST_TIME 2 3971
TEST_TIME 3 4256
TEST_TIME 4 4763
TEST_TIME 5 4953
TEST_TIME 6 5180
TEST_TIME 7 5137
TEST_TIME 8 5164
TEST_TIME 9 5019
TEST_TIME 10 5169
TEST_TIME 11 5185
TEST_TIME 12 5416
TEST_TIME 13 5556
TEST_TIME 14 5749
TEST_TIME 15 6242
TEST_TIME 16 6583
TEST_TIME 17 6527
TEST_TIME 18 7294
TEST_TIME 19 7527
TEST_TIME 20 8408
TEST_TIME 21 9106
TEST_TIME 22 9099
TEST_TIME 23 9388
TEST_TIME 24 9429
TEST_TIME 25 9883
TEST_TIME 26 10127
TEST_TIME 27 11146
TEST_TIME 28 11064
TEST_TIME 29 10955
TEST_TIME 30 10673
TEST_TIME 31 10552
TEST_TIME 32 10635
TEST_TIME 33 10424
TEST_TIME 34 10441
TEST_TIME 35 10314
TEST_TIME 36 10312
TEST_TIME 37 10422
TEST_TIME 38 10333
TEST_TIME 39 10205
TEST_TIME 40 10162
TEST_TIME 41 10101
TEST_TIME 42 9929
TEST_TIME 43 9930
TEST_TIME 44 9856
TEST_TIME 45 9802
TEST_TIME 46 9674
TEST_TIME 47 9711
TEST_TIME 48 9757
TEST_TIME 49 9794
TEST_TIME 50 9888
TEST_TIME 51 10055
TEST_TIME 52 10036
TEST_TIME 53 10057
TEST_TIME 54 10081
TEST_TIME 55 10157
TEST_TIME 56 10285
TEST_TIME 57 10221
TEST_TIME 58 10291
TEST_TIME 59 10236
TEST_TIME 60 10126
TEST_TIME 61 10021
TEST_TIME 62 9969
TEST_TIME 63 9954
TEST_TIME 64 9895
TEST_TIME 65 9839
TEST_TIME 66 9938
TEST_TIME 67 9914
TEST_TIME 68 9880
TEST_TIME 69 9892
TEST_TIME 70 9917
TEST_TIME 71 9975
TEST_TIME 72 9981
TEST_TIME 73 10051
TEST_TIME 74 10100
TEST_TIME 75 10040
TEST_TIME 76 10029
TEST_TIME 77 10158
TEST_TIME 78 10161
TEST_TIME 79 10261
TEST_TIME 80 10141
TEST_TIME 81 10073
TEST_TIME 82 10039
TEST_TIME 83 10078
TEST_TIME 84 9999
TEST_TIME 85 9951
TEST_TIME 86 9940
TEST_TIME 87 9882
TEST_TIME 88 9843
TEST_TIME 89 9880
TEST_TIME 90 9863
TEST_TIME 91 9883
TEST_TIME 92 9855
TEST_TIME 93 9865
TEST_TIME 94 9897
TEST_TIME 95 9927
TEST_TIME 96 9905
TEST_TIME 97 9926
TEST_TIME 98 10015
TEST_TIME 99 10023
TEST_TIME 100 10020
TEST_TIME 101 10033
TEST_TIME 102 10056
TEST_TIME 103 10018
TEST_TIME 104 10090
TEST_TIME 105 10033
TEST_TIME 106 9960
TEST_TIME 107 9939
TEST_TIME 108 9932
TEST_TIME 109 9986
TEST_TIME 110 9927
TEST_TIME 111 9925
TEST_TIME 112 9892
TEST_TIME 113 9864
TEST_TIME 114 9823
TEST_TIME 115 9837
TEST_TIME 116 9813
TEST_TIME 117 9775
TEST_TIME 118 9790
TEST_TIME 119 9810
TEST_TIME 120 9759

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2022 01:39:49 to 20-12-2022 01:39:52.

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 1611408 1611408 0 0.00 0 23068 5 0 0
doc_list_biud 1611407 1611407 0 0.00 0 23937 24 0 0
sp_add_invoice_to_stock 5259 5102 157 2.98 38 178607 16109 0 157
sp_cancel_adding_invoice 6147 6147 0 0.00 4 70485 680 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 12840 334 0 0
sp_cancel_customer_reserve 607375 607375 0 0.00 1 21362 52 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 8766 105 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 9299 88 0 0
sp_cancel_supplier_invoice 6761 6753 8 0.11 1 23513 664 0 8
sp_cancel_supplier_order 442 442 0 0.00 5 1054714 89114 0 0
sp_cancel_write_off 10577 10577 0 0.00 1 13120 94 0 0
sp_client_order 20755 20755 0 0.00 3 36530 357 0 0
sp_customer_reserve 475167 475167 0 0.00 0 78279 158 0 0
sp_fill_shopping_cart 31696 31696 0 0.00 1 19231 37 0 0
sp_fill_shopping_cart_clo_res 475164 475164 0 0.00 0 22865 15 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 26495 344 0 0
sp_kill_cost_storno 17886 17886 0 0.00 0 9101 46 0 0
sp_kill_qstorno_ret_qs2qd 1232522 1232522 0 0.00 0 23460 28 0 0
sp_kill_qty_storno 648329 648329 0 0.00 0 23936 60 0 0
sp_lock_selected_doc 21169 21169 0 0.00 0 7913 5 0 0
sp_make_cost_storno 28259 28259 0 0.00 0 23065 58 0 0
sp_multiply_rows_for_pdistr 28258 28258 0 0.00 0 20847 28 0 0
sp_multiply_rows_for_qdistr 20755 20755 0 0.00 0 36087 172 0 0
sp_pay_from_customer 7215 7215 0 0.00 3 20855 128 0 0
sp_pay_to_supplier 5810 5810 0 0.00 4 9835 138 0 0
sp_payment_common 13025 13025 0 0.00 1 6818 33 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 12832 309 0 0
sp_qd_handle_on_invoice_upd_sts 11245 11245 0 0.00 0 23935 283 0 0
sp_qd_handle_on_reserve_upd_sts 10135 10135 0 0.00 0 12362 53 0 0
sp_reserve_write_off 10135 10135 0 0.00 6 23124 171 0 0
sp_supplier_invoice 5429 5429 0 0.00 7 74481 3215 0 0
sp_supplier_order 5516 5516 0 0.00 5 31317 1190 0 0
srv_aggregate_perf_data 104 104 0 0.00 2935 197596 18380 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1484 151523 45091 0 0
srv_find_qd_qs_mism 1122777 1122777 0 0.00 0 15739 4 0 0
srv_make_invnt_saldo 542 542 0 0.00 48 45616 1063 0 0
srv_make_money_saldo 791 791 0 0.00 1 11225 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 175347 497394 337628 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5102 5102 0 0.00 0 19550 151 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 4699 84 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 3019 11 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 2300 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 629 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 8611 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 1431 20 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 20536 1336 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 6326 20 0 0
t$perf-norm:sp_client_order 20755 20755 0 0.00 0 20318 16 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 1634 6 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 15588 13 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 8239 12 0 0
t$perf-norm:sp_reserve_write_off 10135 10135 0 0.00 0 12675 14 0 0
t$perf-norm:sp_supplier_invoice 5429 5429 0 0.00 0 3355 12 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 7456 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 3279 68 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 3017 46 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 828 17 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 3295 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10508 10194 314 2.98 0 5697 18 0 314
v_all_customers 38127 38127 0 0.00 0 220 1 0 0
v_all_suppliers 30476 30476 0 0.00 0 28 1 0 0
v_all_wares 41510 41510 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 876 8 0 0
v_cancel_client_order 3604 3604 0 0.00 0 3254 5 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3406 11 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 804 4 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 838 3 0 0
v_cancel_supplier_invoice 772 756 16 2.07 0 2486 19 0 16
v_cancel_supplier_order 884 884 0 0.00 0 1055 5 0 0
v_cancel_write_off 2080 2080 0 0.00 0 4550 17 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 511 1 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1430 15 0 0
v_max_id_ord_sup 5425 5425 0 0.00 0 2548 4 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 181 2 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 4247 6 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 7203 7 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 6400 11 0 0
v_min_id_ord_sup 5425 5425 0 0.00 0 5504 9 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 6681 27 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 6541 24 0 0
v_our_firm 10224 10224 0 0.00 0 19 1 0 0
v_reserve_write_off 20270 20270 0 0.00 0 3848 6 0 0
x_lock_depdocs_on_canc_invoice 12522 12522 0 0.00 0 16276 102 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 5718 256 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 2 31295 1118 0 0
x_make_qstorno_1200_2000 5429 5429 0 0.00 3 74468 3078 0 0
x_make_qstorno_2100_3300 475167 475167 0 0.00 0 78276 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 01:39:53 to 20-12-2022 01:39:57.

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
23:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 15167 8192000000 9889514640 10196721664 979295712 10.677 100 85 170 0 1007716672 5940624 22480336
23:10:58 19507 8192000000 9936343824 10253438976 1018821888 11.061 100 92 184 0 1050327536 7381536 24124112
23:12:23 16758 8192000000 9968082336 10293284864 1051289712 11.373 100 91 180 0 1083355456 9381760 22683984
23:13:43 13809 8192000000 10026386160 10370359296 1114013440 11.970 100 81 159 0 1142668288 9323168 19331680
23:15:00 5746 8192000000 10086671248 10444333056 1174442864 12.538 100 83 164 0 1205120544 12140944 18536736
23:16:17 2615 8192000000 10126795888 10496380928 1215920464 12.924 100 79 159 0 1246983328 13878848 17184016
23:17:21 2235 8192000000 10152675920 10523758592 1239746496 13.144 100 83 164 0 1271864992 14583344 17535152
23:18:24 1949 8192000000 10184731136 10563014656 1271567360 13.436 100 85 169 2 1304570912 14067120 18936432
23:19:28 1865 8192000000 10197387280 10585825280 1291107424 13.614 100 76 150 0 1319294128 11872848 16313856
23:20:31 2130 8192000000 10187097296 10591272960 1296922816 13.667 100 60 120 0 1318561888 7668032 13971040
23:21:34 5139 8192000000 10194830416 10608009216 1308797248 13.775 100 27 54 0 1321612448 6455328 6359872
23:22:40 6177 8192000000 10207136032 10620862464 1318855232 13.866 100 24 48 0 1330613152 6775680 4982240
23:23:47 10179 8192000000 10209394176 10628042752 1326902000 13.939 100 11 22 0 1334732192 5397904 2432288
23:25:00 35745 8192000000 10242446656 10646544384 1333514640 13.999 100 48 94 1 1351018928 5661248 11843040
23:26:37 4310 8192000000 10232357456 10648256512 1335459824 14.016 100 35 70 0 1349790976 6902624 7428528
23:27:41 9929 8192000000 10219118720 10639351808 1306314224 13.753 98 22 44 1 1320798000 9759504 4724272
23:28:52 14285 8192000000 10178112992 10587893760 1255882624 13.292 96 24 46 0 1266257872 5022304 5352944
23:30:07 18001 8192000000 10160829056 10568413184 1251946544 13.256 97 20 40 1 1261477856 5581552 3949760
23:31:26 6617 8192000000 10131296608 10528669696 1220422768 12.966 98 28 55 0 1232369520 5721424 6225328
23:32:33 12117 8192000000 10160842976 10563575808 1260577264 13.335 100 24 48 0 1271489472 5491648 5420560
23:33:46 13800 8192000000 10156590288 10563915776 1268456416 13.407 100 39 78 2 1283673296 5482832 9734048
23:35:01 15371 8192000000 10158999312 10570170368 1264484544 13.371 99 15 30 0 1273469744 5474144 3511056
23:36:18 5160 8192000000 10186843312 10594304000 1292414656 13.626 100 36 72 0 1306588800 5282656 8891488
23:37:25 9736 8192000000 10189717712 10609672192 1303833792 13.730 100 11 22 0 1311142144 5157296 2151056
23:38:37 10292 8192000000 10236376544 10641448960 1329503632 13.963 100 34 68 0 1342549488 5016480 8029376
23:39:58 54873 8192000000 10247281952 10666434560 1345202512 14.104 100 43 86 2 1364770864 9934048 9634304
23:41:58 19194 8192000000 10271068384 10687877120 1361368864 14.250 100 57 108 0 1390249392 16554288 12326240
23:43:31 6046 8192000000 10303037248 10720833536 1376952464 14.389 100 57 115 1 1417861712 27739440 13169808
23:44:42 4874 8192000000 10304720656 10730905600 1390845536 14.513 100 30 59 0 1418747248 20736976 7164736
23:45:49 15345 8192000000 10318696528 10746425344 1397995136 14.577 100 30 60 0 1430252976 24808176 7449664
23:47:08 4675 8192000000 10345053872 10764886016 1414492464 14.724 100 62 124 2 1455481536 26185600 14803472
23:48:20 17527 8192000000 10336877072 10768379904 1413105360 14.712 100 42 83 1 1455085680 31774720 10205600
23:49:47 10979 8192000000 10338344624 10760761344 1408642464 14.672 99 57 113 0 1452855072 30589024 13623584
23:51:05 5934 8192000000 10369248192 10795409408 1429676656 14.858 100 57 114 3 1478057584 34557616 13823312
23:52:16 2282 8192000000 10384659488 10801225728 1428443808 14.848 100 67 132 3 1485246384 39800192 17002384
23:53:22 3734 8192000000 10360855888 10778136576 1400450928 14.599 99 65 130 0 1459500640 42683168 16366544
23:54:32 1480 8192000000 10342591904 10741084160 1375105168 14.373 99 87 168 0 1443311728 46949232 21257328
23:55:39 2638 8192000000 10364364256 10766540800 1379865104 14.415 99 84 166 0 1453504256 53480672 20158480
23:56:46 6444 8192000000 10320211456 10736685056 1352646832 14.171 99 58 116 1 1420285792 52284368 15354592
23:58:00 13854 8192000000 10327181856 10750377984 1357314864 14.213 99 36 72 1 1428899984 61470544 10114576
23:59:18 9481 8192000000 10328827552 10757001216 1383625392 14.449 100 34 66 0 1431891680 39256592 9009696
00:00:29 15197 8192000000 10313610640 10746331136 1366449440 14.295 99 30 60 0 1415150880 40268608 8432832
00:01:54 31933 8192000000 10306125216 10749255680 1375234016 14.374 100 24 48 0 1419411072 36840784 7336272
00:03:35 53128 8192000000 10305357856 10757390336 1364180016 14.275 100 18 36 1 1412923744 43317696 5426032
00:05:39 58318 8192000000 10250385856 10688270336 1309780688 13.784 97 15 29 0 1324304192 11453808 3069696
00:07:38 151523 8192000000 10288161888 10726576128 1355738672 14.199 99 33 63 1 1369893248 7791568 6363008
00:11:16 70982 8192000000 10300306608 10749513728 1389304976 14.500 100 24 47 2 1407007744 12337648 5365120
00:13:28 31886 8192000000 10311031088 10752565248 1389049360 14.497 100 27 55 0 1407874176 12552704 6272112
00:15:14 21965 8192000000 10299805616 10761650176 1394975248 14.550 100 7 14 2 1409224160 12389200 1859712
00:16:37 69929 8192000000 10306417792 10763673600 1384032896 14.453 99 9 18 0 1394359808 8521440 1805472
00:18:54 38601 8192000000 10296088048 10765697024 1389235936 14.499 100 14 28 1 1409319632 16579792 3503904
00:20:33 49063 8192000000 10293165584 10758021120 1371041632 14.336 98 35 70 1 1388039568 9376272 7621664
00:22:30 5342 8192000000 10289966816 10753380352 1391930912 14.523 100 16 30 2 1404273312 8324464 4017936
00:23:36 34758 8192000000 10292881664 10754371584 1387312160 14.482 99 19 38 0 1399247008 7645360 4289488
00:25:12 74571 8192000000 10311785776 10788728832 1395579600 14.556 99 11 22 0 1415492880 17452112 2461168
00:27:28 73532 8192000000 10310468192 10774155264 1386198368 14.472 98 13 25 0 1402419744 13155200 3066176
00:29:44 71463 8192000000 10323472400 10781929472 1412581856 14.707 100 21 42 0 1428749264 11735792 4431616
00:31:59 11984 8192000000 10331851984 10773946368 1405036800 14.640 100 57 115 0 1430186800 11426064 13723936
00:33:17 94364 8192000000 10298070576 10760126464 1340421504 14.061 96 12 22 0 1356215232 13632304 2161424
00:35:52 11431 8192000000 10299107808 10747564032 1361317744 14.249 98 34 68 0 1379939072 11226128 7395200
00:37:03 25091 8192000000 10299753328 10741067776 1371510624 14.341 99 48 96 0 1392379184 9828304 11040256
00:38:33 57222 8192000000 10255470768 10714415104 1321067856 13.886 96 34 67 2 1335381248 7074752 7238640
00:40:35 35171 8192000000 10269816704 10720317440 1364119424 14.274 99 43 86 0 1385656640 11700496 9836720
00:42:15 42542 8192000000 10253885936 10715668480 1339034256 14.049 98 5 11 0 1348524704 8620416 870032
00:43:58 79684 8192000000 10238300272 10700750848 1274049136 13.459 93 20 39 0 1287941872 9978176 3914560
00:46:18 72866 8192000000 10248224848 10690723840 1322313376 13.898 98 16 32 0 1334906928 9343968 3249584
00:48:32 67151 8192000000 10275380224 10735202304 1341593840 14.072 98 20 41 0 1362926576 16737744 4594992
00:50:39 75755 8192000000 10247853120 10698493952 1249314752 13.232 92 16 32 0 1270095168 17337744 3442672
00:52:55 53507 8192000000 10289387600 10729619456 1361958208 14.255 100 27 54 0 1385559888 16492704 7108976
00:54:50 66628 8192000000 10284666224 10727841792 1353481184 14.179 99 13 24 0 1372595984 16352896 2761904
00:57:01 81508 8192000000 10281111520 10749054976 1362177552 14.257 99 6 12 0 1380645888 17268448 1199888
00:59:35 61936 8192000000 10269710336 10731032576 1335792672 14.019 97 9 17 0 1349969264 12599936 1576656
01:01:37 52229 8192000000 10247658288 10709008384 1339179104 14.050 98 11 22 0 1351276576 9587472 2510000
01:03:30 43510 8192000000 10262163456 10723020800 1358168832 14.221 99 17 35 0 1371945792 9818736 3958224
01:05:14 59208 8192000000 10278904016 10729181184 1368062336 14.310 99 22 44 0 1381087072 7629600 5395136
01:07:19 57356 8192000000 10289038560 10747301888 1378945632 14.407 100 13 25 0 1391194480 9128272 3120576
01:09:17 50544 8192000000 10288264912 10758254592 1390084048 14.507 100 8 16 0 1405571024 13889088 1597888
01:11:08 76238 8192000000 10319292992 10775044096 1401297024 14.607 100 20 40 0 1415924272 10200528 4426720
01:13:24 42193 8192000000 10313155936 10777579520 1395635808 14.556 99 24 48 0 1418423920 17182720 5605392
01:15:10 61444 8192000000 10315918704 10770231296 1379185120 14.409 98 24 48 0 1402396128 17995248 5215760
01:17:15 41749 8192000000 10311687984 10765254656 1375592352 14.377 98 24 48 0 1397029312 15769040 5667920
01:19:01 54693 8192000000 10302293632 10754080768 1358976896 14.228 97 14 28 0 1375183056 13319808 2886352
01:20:58 71009 8192000000 10336836992 10781814784 1409071344 14.676 100 35 68 0 1428632560 12256528 7304688
01:23:10 80093 8192000000 10292883584 10736574464 1342997344 14.084 96 14 26 0 1353083568 7099136 2987088
01:25:44 64606 8192000000 10269112672 10730434560 1348761072 14.136 98 35 70 0 1374858784 17682160 8415552
01:27:52 52580 8192000000 10265545840 10738159616 1362453696 14.259 100 16 32 1 1383855504 18103088 3298720
01:29:55 32843 8192000000 10261979120 10713841664 1341494528 14.071 98 18 36 0 1355146976 9564512 4087936
01:31:29 83911 8192000000 10233377776 10688380928 1276246208 13.479 94 6 12 0 1286815504 8996496 1572800
01:34:03 73981 8192000000 10242814768 10712039424 1314530112 13.827 97 8 16 0 1328977920 12503776 1944032
01:36:18 99035 8192000000 10269856704 10728456192 1295682592 13.656 95 16 32 0 1311095440 11805344 3607504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-12-2022 01:39:59 to 20-12-2022 01:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 01:40:16 to 20-12-2022 01:40:18.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241306
MON$OLDEST_ACTIVE               242895
MON$OLDEST_SNAPSHOT             242895
MON$NEXT_TRANSACTION            242895
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-03 16:41:55.3470
MON$PAGES                       2029664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:907: 
:908: mscs-test-161.budzdorov.ru    Tue Dec 20 01:38:39 2022
:909:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:910: 
--- end of diff output ---

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

20.12.22 01:40:19. There are 3 logs in /var/tmp/logs-oltp30 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-12-20 01:40:21 No severe PSQL-related problems occured
20.12.22 01:40:23. End of report.