Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 04: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 16148780 kB
MemAvailable 31226460 kB
Buffers 20 kB
Cached 15529712 kB
SwapCached 2484 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-06-22 04:37:52
DTS_END                         2022-06-22 04:37:19.9660
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/39956
TRN_ID                          223552
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: 8500.88 19176 1020105
customer order: creation 172.58 471 20709
customer order: refuse 14.49 384 1739
order to supplier: creation 45.12 1348 5414
order to supplier: removal 3.21 156269 385
invoice (draft): creation 45.21 3228 5425
invoice (draft): removal 45.65 952 5478
invoice accept: apply 41.67 28406 5000
invoice accept: cancel 41.02 1208 4922
customer reserve: creation 3816.60 276 457992
customer reserve: removal 3982.75 100 477930
realization accept: apply 83.93 286 10072
realization accept: cancel 78.20 162 9384
payment from customer: creation 59.73 185 7168
payment from customer: removal 5.48 158 658
payment to supplier: creation 48.88 189 5866
payment to supplier: removal 3.72 140 446
service: total inventory turnovers 3.98 4065 477
service: total monetary turnovers 7.68 384 922
service: aggregate perf. data 0.95 14750 114
service: refresh index statistics 0.03 170550 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 04:39:32 to 22-06-2022 04:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8503
TEST_TIME 2 8496
TEST_TIME 3 7232
TEST_TIME 4 6996
TEST_TIME 5 6917
TEST_TIME 6 6877
TEST_TIME 7 7068
TEST_TIME 8 7267
TEST_TIME 9 7332
TEST_TIME 10 7241
TEST_TIME 11 7460
TEST_TIME 12 7636
TEST_TIME 13 7838
TEST_TIME 14 7771
TEST_TIME 15 7866
TEST_TIME 16 7950
TEST_TIME 17 7991
TEST_TIME 18 8094
TEST_TIME 19 8352
TEST_TIME 20 8277
TEST_TIME 21 8162
TEST_TIME 22 8206
TEST_TIME 23 8921
TEST_TIME 24 9373
TEST_TIME 25 9208
TEST_TIME 26 9454
TEST_TIME 27 9874
TEST_TIME 28 9805
TEST_TIME 29 10204
TEST_TIME 30 10684
TEST_TIME 31 11165
TEST_TIME 32 11140
TEST_TIME 33 10962
TEST_TIME 34 10700
TEST_TIME 35 10685
TEST_TIME 36 10582
TEST_TIME 37 10530
TEST_TIME 38 10477
TEST_TIME 39 10396
TEST_TIME 40 10272
TEST_TIME 41 10130
TEST_TIME 42 9968
TEST_TIME 43 9922
TEST_TIME 44 9832
TEST_TIME 45 9747
TEST_TIME 46 9652
TEST_TIME 47 9544
TEST_TIME 48 9441
TEST_TIME 49 9373
TEST_TIME 50 9350
TEST_TIME 51 9271
TEST_TIME 52 9253
TEST_TIME 53 9185
TEST_TIME 54 9073
TEST_TIME 55 8961
TEST_TIME 56 8999
TEST_TIME 57 9000
TEST_TIME 58 8969
TEST_TIME 59 8949
TEST_TIME 60 8899
TEST_TIME 61 8926
TEST_TIME 62 8952
TEST_TIME 63 8994
TEST_TIME 64 9055
TEST_TIME 65 9066
TEST_TIME 66 9015
TEST_TIME 67 8986
TEST_TIME 68 8990
TEST_TIME 69 9057
TEST_TIME 70 9015
TEST_TIME 71 9038
TEST_TIME 72 9114
TEST_TIME 73 9048
TEST_TIME 74 9004
TEST_TIME 75 8908
TEST_TIME 76 8938
TEST_TIME 77 8875
TEST_TIME 78 8889
TEST_TIME 79 8832
TEST_TIME 80 8772
TEST_TIME 81 8721
TEST_TIME 82 8703
TEST_TIME 83 8728
TEST_TIME 84 8778
TEST_TIME 85 8755
TEST_TIME 86 8737
TEST_TIME 87 8801
TEST_TIME 88 8825
TEST_TIME 89 8811
TEST_TIME 90 8820
TEST_TIME 91 8904
TEST_TIME 92 8898
TEST_TIME 93 8906
TEST_TIME 94 8885
TEST_TIME 95 8818
TEST_TIME 96 8803
TEST_TIME 97 8771
TEST_TIME 98 8723
TEST_TIME 99 8683
TEST_TIME 100 8647
TEST_TIME 101 8646
TEST_TIME 102 8620
TEST_TIME 103 8589
TEST_TIME 104 8589
TEST_TIME 105 8559
TEST_TIME 106 8557
TEST_TIME 107 8523
TEST_TIME 108 8532
TEST_TIME 109 8583
TEST_TIME 110 8682
TEST_TIME 111 8707
TEST_TIME 112 8728
TEST_TIME 113 8766
TEST_TIME 114 8716
TEST_TIME 115 8680
TEST_TIME 116 8633
TEST_TIME 117 8615
TEST_TIME 118 8602
TEST_TIME 119 8564
TEST_TIME 120 8523

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2022 04:39:36 to 22-06-2022 04:39: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 1441065 1441065 0 0.00 0 19799 15 0 0
doc_list_biud 1441058 1441058 0 0.00 0 29544 39 0 0
sp_add_invoice_to_stock 5109 5000 109 2.13 61 262421 28406 0 109
sp_cancel_adding_invoice 4922 4922 0 0.00 6 72303 1208 0 0
sp_cancel_client_order 1739 1739 0 0.00 6 10511 384 0 0
sp_cancel_customer_reserve 477930 477930 0 0.00 1 29547 100 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 4 6464 158 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 5064 141 0 0
sp_cancel_supplier_invoice 5482 5478 4 0.07 2 24241 953 0 4
sp_cancel_supplier_order 385 385 0 0.00 8 1436452 156270 0 0
sp_cancel_write_off 9384 9384 0 0.00 2 9424 163 0 0
sp_client_order 20709 20709 0 0.00 6 23975 471 0 0
sp_customer_reserve 457992 457992 0 0.00 1 100242 276 0 0
sp_fill_shopping_cart 31535 31535 0 0.00 1 20305 88 0 0
sp_fill_shopping_cart_clo_res 457963 457963 0 0.00 0 16447 22 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 40705 377 0 0
sp_kill_cost_storno 15410 15410 0 0.00 0 14313 67 0 0
sp_kill_qstorno_ret_qs2qd 971107 971107 0 0.00 0 29065 43 0 0
sp_kill_qty_storno 514875 514875 0 0.00 0 29544 100 0 0
sp_lock_selected_doc 20791 20791 0 0.00 0 6647 7 0 0
sp_make_cost_storno 28071 28071 0 0.00 0 14245 84 0 0
sp_multiply_rows_for_pdistr 28071 28071 0 0.00 0 14365 40 0 0
sp_multiply_rows_for_qdistr 20709 20709 0 0.00 0 20319 174 0 0
sp_pay_from_customer 7168 7168 0 0.00 6 14373 186 0 0
sp_pay_to_supplier 5866 5866 0 0.00 7 12276 189 0 0
sp_payment_common 13034 13034 0 0.00 2 9223 50 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 1 10503 313 0 0
sp_qd_handle_on_invoice_upd_sts 9887 9887 0 0.00 0 24107 495 0 0
sp_qd_handle_on_reserve_upd_sts 10072 10072 0 0.00 0 21068 62 0 0
sp_reserve_write_off 10072 10072 0 0.00 7 21489 287 0 0
sp_supplier_invoice 5425 5425 0 0.00 8 84203 3228 0 0
sp_supplier_order 5414 5414 0 0.00 7 58724 1349 0 0
srv_aggregate_perf_data 114 114 0 0.00 2428 208988 14751 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 798 162558 39902 0 0
srv_find_qd_qs_mism 970911 970911 0 0.00 0 19799 12 0 0
srv_make_invnt_saldo 477 477 0 0.00 55 57545 4066 0 0
srv_make_money_saldo 922 922 0 0.00 1 14873 385 0 0
srv_recalc_idx_stat 4 4 0 0.00 90243 233828 170550 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5005 5005 0 0.00 1 24899 963 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 2 6214 465 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 9979 39 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 11822 54 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 705 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 314 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 6264 49 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 112336 6779 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 2989 21 0 0
t$perf-norm:sp_client_order 20709 20709 0 0.00 0 14768 37 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 3623 26 0 0
t$perf-norm:sp_pay_from_customer 7168 7168 0 0.00 0 10872 20 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 8282 20 0 0
t$perf-norm:sp_reserve_write_off 10073 10073 0 0.00 0 9692 23 0 0
t$perf-norm:sp_supplier_invoice 5425 5425 0 0.00 0 10136 34 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 8970 34 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 476 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 319 9 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 1374 13 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 758 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 180 70 0 0
v_add_invoice_to_stock 10146 9928 218 2.14 0 8234 25 0 218
v_all_customers 38168 38168 0 0.00 0 98 4 0 0
v_all_suppliers 29932 29932 0 0.00 0 101 4 0 0
v_all_wares 41416 41416 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 3261 17 0 0
v_cancel_client_order 3478 3478 0 0.00 0 2529 11 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2529 16 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 2411 14 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 4879 22 0 0
v_cancel_supplier_invoice 818 810 8 0.97 0 1827 24 0 8
v_cancel_supplier_order 770 770 0 0.00 0 1940 17 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3217 26 0 0
v_max_id_clo_ord 5411 5411 0 0.00 0 3209 5 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 5496 16 0 0
v_max_id_ord_sup 5416 5416 0 0.00 0 4030 6 0 0
v_max_non_paid_invoice 1727 1727 0 0.00 0 78 7 0 0
v_max_non_paid_realizn 3688 3688 0 0.00 0 6251 9 0 0
v_min_id_clo_ord 5411 5411 0 0.00 0 4707 10 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 8550 27 0 0
v_min_id_ord_sup 5416 5416 0 0.00 0 4649 10 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 7776 17 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 9203 23 0 0
v_our_firm 10208 10208 0 0.00 0 55 4 0 0
v_reserve_write_off 20144 20144 0 0.00 0 4816 12 0 0
x_lock_depdocs_on_canc_invoice 9995 9995 0 0.00 0 13888 137 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 3822 341 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 3 58292 1174 0 0
x_make_qstorno_1200_2000 5425 5425 0 0.00 4 84184 2978 0 0
x_make_qstorno_2100_3300 457992 457992 0 0.00 0 100217 241 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 04:39:40 to 22-06-2022 04:39:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 28223 8192000000 9718448272 10016616448 798972816 8.886 100 88 176 0 829067184 8440768 21653600
02:09:56 20267 8192000000 9766400032 10075725824 842850608 9.328 100 91 182 0 877544528 13388128 21305792
02:11:18 16505 8192000000 9796348784 10117287936 865159136 9.552 100 81 162 0 907275744 23624000 18492608
02:12:38 9496 8192000000 9844606592 10178461696 902264480 9.921 100 77 152 0 960431776 40984096 17183200
02:13:51 6378 8192000000 9915731984 10258075648 947444960 10.366 100 81 158 2 1028535088 64390256 16699872
02:15:01 1773 8192000000 9971388368 10322862080 985454704 10.737 100 91 175 0 1081021568 78415072 17151792
02:16:04 1091 8192000000 9986062016 10345926656 1014554544 11.019 100 99 192 0 1102571264 69120816 18895904
02:17:07 1112 8192000000 9982806608 10349273088 1033581552 11.203 100 80 153 2 1105567824 56665744 15320528
02:18:09 1015 8192000000 10038025648 10412896256 1058838976 11.445 100 94 183 1 1153910576 77163216 17908384
02:19:11 1003 8192000000 10027238224 10411872256 1070319024 11.555 100 92 176 1 1151530848 62581584 18630240
02:20:12 1040 8192000000 10063504512 10448994304 1081349024 11.660 100 96 178 3 1188162752 88407136 18406592
02:21:15 1210 8192000000 10032553872 10424725504 1090755024 11.750 100 79 156 0 1152083520 45307840 16020656
02:22:17 1336 8192000000 10047514224 10433441792 1094836576 11.789 100 94 180 0 1170464256 57033904 18593776
02:23:19 11009 8192000000 10066506432 10469044224 1093194720 11.773 100 61 122 0 1187612960 82368832 12049408
02:24:34 1086 8192000000 10060659408 10449367040 1094269744 11.783 100 95 183 0 1182937936 69170768 19497424
02:25:36 2016 8192000000 10022043760 10415452160 1097520752 11.814 100 70 134 0 1141148160 30144832 13482576
02:26:39 2510 8192000000 10052392256 10446020608 1101449936 11.851 100 72 134 3 1178590192 63551568 13588688
02:27:43 1400 8192000000 10063738240 10458427392 1106057984 11.895 100 87 171 1 1186539728 63938816 16542928
02:28:46 3325 8192000000 10059695040 10454216704 1099392976 11.832 100 68 131 1 1177987536 65648528 12946032
02:29:53 1102 8192000000 10039496288 10423021568 1087726736 11.721 100 90 176 1 1159847664 54543488 17577440
02:30:55 1763 8192000000 10005701408 10384207872 1051158416 11.372 100 83 162 1 1125489120 57256400 17074304
02:31:57 909 8192000000 10012572992 10392301568 1054809776 11.407 100 94 169 0 1137612352 64003776 18798800
02:33:00 1181 8192000000 10032459792 10413527040 1060681936 11.463 100 89 164 2 1154055680 76788576 16585168
02:34:02 1131 8192000000 9995990304 10374242304 1045537056 11.318 100 91 171 0 1121507344 59243344 16726944
02:35:04 1035 8192000000 9996196688 10371231744 1036338800 11.229 99 90 173 0 1116044112 61648544 18056768
02:36:06 4773 8192000000 9991485472 10370441216 1032525536 11.193 99 69 133 1 1111187584 65086736 13575312
02:37:11 3817 8192000000 9992500800 10377998336 1049336208 11.354 100 62 117 3 1121942160 60621712 11984240
02:38:20 1303 8192000000 10006940304 10390061056 1067827984 11.531 100 69 131 1 1131139520 49649392 13662144
02:39:24 925 8192000000 10062100384 10442899456 1089049360 11.734 100 82 152 1 1177209840 72830528 15329952
02:40:29 11047 8192000000 10078359488 10470625280 1093641456 11.777 100 63 117 3 1191162064 85403616 12116992
02:41:43 7594 8192000000 10102646016 10498834432 1102298016 11.859 100 71 140 0 1221105776 103594912 15212848
02:43:02 3809 8192000000 10125507040 10520252416 1124549552 12.070 100 90 165 2 1239978928 99318560 16110816
02:44:11 1422 8192000000 10157939504 10559979520 1134271088 12.162 100 77 150 0 1283186624 131597920 17317616
02:45:23 3887 8192000000 10187405632 10589605888 1150707168 12.316 100 83 162 2 1310447664 141540048 18200448
02:46:29 1471 8192000000 10214701984 10612961280 1151045616 12.319 100 86 169 1 1335229584 165407344 18776624
02:47:34 1589 8192000000 10220699184 10632830976 1162126016 12.423 100 86 165 2 1327859088 146917648 18815424
02:48:38 1501 8192000000 10261260272 10673119232 1172564304 12.521 100 85 163 2 1376970352 186095680 18310368
02:49:42 1395 8192000000 10300432048 10710269952 1179502320 12.586 100 89 176 0 1403150816 202600688 21047808
02:50:47 3549 8192000000 10300577072 10709536768 1165954352 12.459 99 89 172 1 1416164928 229957968 20252608
02:52:00 3384 8192000000 10339922576 10751770624 1176754896 12.560 100 93 177 1 1443562448 247891792 18915760
02:53:09 2553 8192000000 10360298784 10778939392 1188875328 12.673 100 83 156 0 1469122544 262701616 17545600
02:54:15 1287 8192000000 10376353856 10779754496 1175944320 12.552 100 98 186 0 1486700256 288248512 22507424
02:55:21 943 8192000000 10436991808 10848104448 1188269312 12.667 100 97 193 1 1544001584 332516224 23216048
02:56:25 1002 8192000000 10434086848 10865557504 1187273344 12.658 100 98 192 1 1537866896 328328400 22265152
02:57:28 792 8192000000 10463986288 10872356864 1185921328 12.645 100 96 191 1 1572923088 364522608 22479152
02:58:32 968 8192000000 10511312000 10925568000 1183875456 12.626 100 93 182 3 1622014048 415671376 22467216
02:59:35 1128 8192000000 10498903424 10987499520 1189071584 12.675 100 99 195 1 1603515040 390226528 24216928
03:00:38 1137 8192000000 10478381984 10966069248 1189217200 12.676 100 92 173 1 1582589856 372077696 21294960
03:01:41 6680 8192000000 10467940512 10923405312 1178078576 12.572 100 52 104 0 1569408112 377999536 13330000
03:02:55 12121 8192000000 10462890432 10929045504 1174131536 12.535 100 35 70 1 1572344640 388057984 10155120
03:04:10 15963 8192000000 10381262272 10888859648 1153517120 12.342 99 35 71 2 1487308560 324653216 9138224
03:05:28 38517 8192000000 10327855808 10788868096 1122205344 12.048 98 17 34 0 1431131824 304176880 4749600
03:07:17 39662 8192000000 10179311632 10637713408 1087809088 11.722 97 18 36 2 1284259776 192187008 4263680
03:08:59 26930 8192000000 10106633408 10567520256 1137554464 12.193 99 19 38 0 1237805216 96342288 3908464
03:10:31 112892 8192000000 10137634832 10589573120 1132842096 12.148 100 50 99 2 1229628160 84943648 11842416
03:13:28 162557 8192000000 10070940160 10519511040 1106167984 11.896 97 16 33 0 1220356880 110474656 3714240
03:17:11 89401 8192000000 10062344768 10508042240 1103496416 11.871 97 22 42 2 1141463552 33688112 4279024
03:19:42 73784 8192000000 10034015776 10488811520 1096383408 11.803 98 18 36 0 1154818208 54341744 4093056
03:22:09 126092 8192000000 10062828816 10500681728 1127590048 12.099 100 31 61 1 1153613344 19676368 6346928
03:25:18 88170 8192000000 10079401152 10543144960 1104149776 11.877 98 22 43 0 1166471712 57732800 4589136
03:27:47 6012 8192000000 10094672080 10520956928 1131740096 12.138 100 71 139 1 1191889360 44644288 15504976
03:28:55 17345 8192000000 10107549248 10551324672 1136861824 12.186 100 30 58 2 1218796736 75607888 6327024
03:30:16 97737 8192000000 10117904928 10587840512 1144368640 12.257 100 14 28 0 1231180288 83031232 3780416
03:34:34 30352 8192000000 10050962032 10486521856 1104046640 11.876 97 42 73 2 1126928208 14477008 8404560
03:36:05 53309 8192000000 10088916720 10552537088 1133082944 12.150 100 19 37 0 1194608816 57138752 4387120
03:38:10 13186 8192000000 10040263248 10473582592 1093542480 11.776 97 29 54 0 1125021456 25178992 6299984
03:39:24 36121 8192000000 10089375568 10523553792 1123573264 12.061 100 35 68 1 1206211488 75778656 6859568
03:41:11 61635 8192000000 10061196176 10551066624 1063434080 11.489 94 20 40 1 1123503792 55886624 4183088
03:43:18 14948 8192000000 10056524400 10474819584 1115760368 11.987 100 73 144 0 1158733488 28497504 14475616
03:44:33 21577 8192000000 10111422688 10537168896 1115417184 11.984 99 52 104 1 1189440464 63727072 10296208
03:46:00 35239 8192000000 10088130992 10532802560 1120155280 12.028 100 46 92 1 1197981568 67918576 9907712
03:47:39 24845 8192000000 10133701808 10557382656 1120779712 12.034 100 45 88 2 1214922240 84950848 9191680
03:49:08 24592 8192000000 10094906528 10517561344 1109512864 11.928 99 39 78 1 1177688736 60315120 7860752
03:50:35 131189 8192000000 10083689552 10517905408 1121184544 12.038 99 38 76 0 1190589392 61739360 7665488
03:53:48 52259 8192000000 10076029264 10545258496 1098104688 11.820 97 11 22 0 1159044544 58551728 2388128
03:55:41 60727 8192000000 10055055040 10518437888 1121145968 12.038 99 13 26 0 1162349200 38389904 2813328
03:57:45 77884 8192000000 10133911584 10598907904 1136197488 12.180 100 18 36 0 1219960432 79495760 4267184
04:00:11 69812 8192000000 10096305984 10568716288 1140081584 12.216 100 19 38 0 1218510176 74253776 4174816
04:02:22 66029 8192000000 10097238608 10567028736 1102516880 11.862 96 20 40 0 1168709536 61486912 4705744
04:04:43 45494 8192000000 10047909104 10493894656 1084510880 11.690 96 23 46 1 1138648144 49459936 4677328
04:06:32 21269 8192000000 10104199632 10522812416 1104273968 11.878 98 56 102 0 1177989200 62423872 11291360
04:08:45 78866 8192000000 10081136496 10513309696 1103413024 11.870 98 31 62 2 1162350544 53329600 5607920
04:11:06 32889 8192000000 10112403312 10526375936 1135041296 12.169 100 51 101 0 1200349632 55225648 10082688
04:12:40 78693 8192000000 10074437424 10542174208 1119366576 12.021 98 33 66 0 1178997776 53525088 6106112
04:15:37 104062 8192000000 10089949264 10569531392 1135288976 12.171 100 10 20 0 1188571552 51035728 2246848
04:18:22 80202 8192000000 10087039760 10538086400 1114137728 11.972 97 17 34 0 1171431920 53455456 3838736
04:20:42 62621 8192000000 10119088816 10561810432 1109092640 11.924 97 24 47 2 1202240976 87879424 5268912
04:22:47 50466 8192000000 10189424960 10641891328 1142944336 12.243 100 25 50 0 1267918560 119230832 5743392
04:24:42 43787 8192000000 10211164928 10669563904 1126770672 12.091 98 38 75 0 1310071728 174562224 8738832
04:27:04 73363 8192000000 10082942864 10555301888 1118088512 12.009 98 24 48 1 1215729056 92395408 5245136
04:29:19 106018 8192000000 10072443248 10539040768 1083635344 11.682 95 13 26 0 1151857712 65631840 2590528
04:32:08 74929 8192000000 10088764880 10532958208 1135275200 12.171 100 48 94 0 1197359488 52728512 9355776
04:34:34 40374 8192000000 10097946288 10541686784 1094416400 11.785 96 31 62 1 1159854752 59259232 6179120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-06-2022 04:39:46 to 22-06-2022 04:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 04:40:03 to 22-06-2022 04:40:05.

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          230999
MON$OLDEST_ACTIVE               231000
MON$OLDEST_SNAPSHOT             231000
MON$NEXT_TRANSACTION            231000
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                       2046368
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

22.06.22 04:40:06. 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-06-22 04:40:08 No severe PSQL-related problems occured
22.06.22 04:40:10. End of report.