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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16265200 kB
MemAvailable 31398248 kB
Buffers 20 kB
Cached 15419116 kB
SwapCached 2544 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-25 04:37:18
DTS_END                         2022-05-25 04:37:18.3260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34368
TRN_ID                          219677
ATT_ID                          645
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: 9043.26 20345 1085191
customer order: creation 170.88 468 20506
customer order: refuse 14.41 380 1729
order to supplier: creation 45.04 1348 5405
order to supplier: removal 3.50 157672 420
invoice (draft): creation 43.90 3143 5268
invoice (draft): removal 49.53 1117 5944
invoice accept: apply 42.07 26939 5048
invoice accept: cancel 44.78 1193 5374
customer reserve: creation 3948.31 257 473797
customer reserve: removal 4398.19 98 527783
realization accept: apply 81.14 271 9737
realization accept: cancel 74.14 176 8897
payment from customer: creation 58.29 167 6995
payment from customer: removal 5.78 129 694
payment to supplier: creation 47.78 204 5734
payment to supplier: removal 3.60 210 432
service: total inventory turnovers 3.86 4138 463
service: total monetary turnovers 7.08 367 850
service: aggregate perf. data 0.93 15271 111
service: refresh index statistics 0.03 193348 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-05-2022 04:39:04 to 25-05-2022 04:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7910
TEST_TIME 2 7205
TEST_TIME 3 6495
TEST_TIME 4 6391
TEST_TIME 5 6118
TEST_TIME 6 6021
TEST_TIME 7 6182
TEST_TIME 8 6116
TEST_TIME 9 6297
TEST_TIME 10 6383
TEST_TIME 11 6286
TEST_TIME 12 6229
TEST_TIME 13 6232
TEST_TIME 14 6407
TEST_TIME 15 6552
TEST_TIME 16 6668
TEST_TIME 17 6746
TEST_TIME 18 6900
TEST_TIME 19 7001
TEST_TIME 20 7413
TEST_TIME 21 7410
TEST_TIME 22 7485
TEST_TIME 23 7737
TEST_TIME 24 7774
TEST_TIME 25 8386
TEST_TIME 26 8749
TEST_TIME 27 9144
TEST_TIME 28 9774
TEST_TIME 29 9987
TEST_TIME 30 10185
TEST_TIME 31 10394
TEST_TIME 32 10480
TEST_TIME 33 10725
TEST_TIME 34 10910
TEST_TIME 35 10738
TEST_TIME 36 10696
TEST_TIME 37 10562
TEST_TIME 38 10473
TEST_TIME 39 10322
TEST_TIME 40 10364
TEST_TIME 41 10242
TEST_TIME 42 10175
TEST_TIME 43 10031
TEST_TIME 44 9955
TEST_TIME 45 9863
TEST_TIME 46 9940
TEST_TIME 47 9826
TEST_TIME 48 9827
TEST_TIME 49 9761
TEST_TIME 50 9654
TEST_TIME 51 9690
TEST_TIME 52 9640
TEST_TIME 53 9573
TEST_TIME 54 9636
TEST_TIME 55 9572
TEST_TIME 56 9548
TEST_TIME 57 9750
TEST_TIME 58 9661
TEST_TIME 59 9664
TEST_TIME 60 9757
TEST_TIME 61 9715
TEST_TIME 62 9695
TEST_TIME 63 9667
TEST_TIME 64 9775
TEST_TIME 65 9795
TEST_TIME 66 9784
TEST_TIME 67 9791
TEST_TIME 68 9784
TEST_TIME 69 9776
TEST_TIME 70 9817
TEST_TIME 71 9831
TEST_TIME 72 9719
TEST_TIME 73 9685
TEST_TIME 74 9618
TEST_TIME 75 9623
TEST_TIME 76 9586
TEST_TIME 77 9540
TEST_TIME 78 9482
TEST_TIME 79 9484
TEST_TIME 80 9437
TEST_TIME 81 9371
TEST_TIME 82 9296
TEST_TIME 83 9261
TEST_TIME 84 9351
TEST_TIME 85 9324
TEST_TIME 86 9278
TEST_TIME 87 9313
TEST_TIME 88 9401
TEST_TIME 89 9358
TEST_TIME 90 9337
TEST_TIME 91 9340
TEST_TIME 92 9398
TEST_TIME 93 9401
TEST_TIME 94 9382
TEST_TIME 95 9441
TEST_TIME 96 9418
TEST_TIME 97 9350
TEST_TIME 98 9302
TEST_TIME 99 9278
TEST_TIME 100 9229
TEST_TIME 101 9174
TEST_TIME 102 9142
TEST_TIME 103 9138
TEST_TIME 104 9119
TEST_TIME 105 9134
TEST_TIME 106 9107
TEST_TIME 107 9094
TEST_TIME 108 9076
TEST_TIME 109 9055
TEST_TIME 110 9047
TEST_TIME 111 9070
TEST_TIME 112 9100
TEST_TIME 113 9207
TEST_TIME 114 9194
TEST_TIME 115 9242
TEST_TIME 116 9181
TEST_TIME 117 9134
TEST_TIME 118 9173
TEST_TIME 119 9135
TEST_TIME 120 9064

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-05-2022 04:39:08 to 25-05-2022 04:39:11.

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 1520405 1520405 0 0.00 0 43011 14 0 0
doc_list_biud 1520391 1520391 0 0.00 0 121016 40 0 0
sp_add_invoice_to_stock 5207 5048 159 3.05 66 240719 26940 0 159
sp_cancel_adding_invoice 5374 5374 0 0.00 6 73313 1193 0 0
sp_cancel_client_order 1729 1729 0 0.00 7 11371 381 0 0
sp_cancel_customer_reserve 527783 527783 0 0.00 1 33852 99 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 20559 130 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 37271 211 0 0
sp_cancel_supplier_invoice 5956 5944 12 0.20 3 121032 1117 0 12
sp_cancel_supplier_order 420 420 0 0.00 18 1414531 157673 0 0
sp_cancel_write_off 8897 8897 0 0.00 2 21864 177 0 0
sp_client_order 20506 20506 0 0.00 6 40165 469 0 0
sp_customer_reserve 473797 473797 0 0.00 1 115835 257 0 0
sp_fill_shopping_cart 31166 31166 0 0.00 1 10839 72 0 0
sp_fill_shopping_cart_clo_res 473757 473757 0 0.00 0 18301 20 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 33490 283 0 0
sp_kill_cost_storno 15397 15397 0 0.00 0 43003 74 0 0
sp_kill_qstorno_ret_qs2qd 1070827 1070827 0 0.00 0 121014 44 0 0
sp_kill_qty_storno 564884 564884 0 0.00 0 121016 101 0 0
sp_lock_selected_doc 20509 20509 0 0.00 0 8834 9 0 0
sp_make_cost_storno 27468 27468 0 0.00 0 14403 81 0 0
sp_multiply_rows_for_pdistr 27465 27465 0 0.00 0 7324 36 0 0
sp_multiply_rows_for_qdistr 20506 20506 0 0.00 0 14836 170 0 0
sp_pay_from_customer 6995 6995 0 0.00 6 17318 167 0 0
sp_pay_to_supplier 5734 5734 0 0.00 6 27957 205 0 0
sp_payment_common 12727 12727 0 0.00 2 8142 40 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 1 11299 314 0 0
sp_qd_handle_on_invoice_upd_sts 10376 10376 0 0.00 0 38218 493 0 0
sp_qd_handle_on_reserve_upd_sts 9735 9735 0 0.00 0 9741 61 0 0
sp_reserve_write_off 9737 9737 0 0.00 9 21873 272 0 0
sp_supplier_invoice 5268 5268 0 0.00 9 114763 3143 0 0
sp_supplier_order 5405 5405 0 0.00 7 48497 1348 0 0
srv_aggregate_perf_data 111 111 0 0.00 3096 175728 15271 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 809 142330 35454 0 0
srv_find_qd_qs_mism 1035725 1035725 0 0.00 0 17231 11 0 0
srv_make_invnt_saldo 463 463 0 0.00 64 107574 4138 0 0
srv_make_money_saldo 850 850 0 0.00 1 16149 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 57379 374829 193348 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5050 5050 0 0.00 1 9740 771 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 5763 420 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 7892 29 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1823 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 2237 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 750 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 2932 41 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 76387 5784 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 3432 19 0 0
t$perf-norm:sp_client_order 20507 20507 0 0.00 0 7907 33 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 2650 15 0 0
t$perf-norm:sp_pay_from_customer 6995 6995 0 0.00 0 3778 18 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 10110 20 0 0
t$perf-norm:sp_reserve_write_off 9737 9737 0 0.00 0 8768 20 0 0
t$perf-norm:sp_supplier_invoice 5268 5268 0 0.00 0 5722 27 0 0
t$perf-norm:sp_supplier_order 5405 5405 0 0.00 0 2343 27 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 517 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1637 30 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 537 12 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 2296 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 363 97 0 0
v_add_invoice_to_stock 10320 10002 318 3.08 0 8587 27 0 318
v_all_customers 37314 37314 0 0.00 0 79 3 0 0
v_all_suppliers 29834 29834 0 0.00 0 70 4 0 0
v_all_wares 41000 41000 0 0.00 0 86 3 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 1075 13 0 0
v_cancel_client_order 3458 3458 0 0.00 0 3819 11 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 20475 25 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 2714 13 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 37188 56 0 0
v_cancel_supplier_invoice 854 830 24 2.81 0 1161 25 0 24
v_cancel_supplier_order 840 840 0 0.00 0 2622 15 0 0
v_cancel_write_off 1972 1972 0 0.00 0 21685 31 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 378 4 0 0
v_max_id_clo_res 966 966 0 0.00 0 1073 12 0 0
v_max_id_ord_sup 5262 5262 0 0.00 0 5944 9 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 1251 8 0 0
v_max_non_paid_realizn 3651 3651 0 0.00 0 523 6 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 1763 7 0 0
v_min_id_clo_res 966 966 0 0.00 0 4418 17 0 0
v_min_id_ord_sup 5261 5261 0 0.00 0 6710 13 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 8124 17 0 0
v_min_non_paid_realizn 6994 6994 0 0.00 0 4149 14 0 0
v_our_firm 10372 10372 0 0.00 0 63 4 0 0
v_reserve_write_off 19470 19470 0 0.00 0 21626 13 0 0
x_lock_depdocs_on_canc_invoice 10903 10903 0 0.00 0 10921 115 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 25614 400 0 0
x_make_qstorno_1000_1200 5405 5405 0 0.00 3 48458 1204 0 0
x_make_qstorno_1200_2000 5268 5268 0 0.00 4 113760 2937 0 0
x_make_qstorno_2100_3300 473797 473797 0 0.00 0 115826 226 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-05-2022 04:39:12 to 25-05-2022 04:39:17.

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:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 21521 8192000000 9729586048 10028183552 812270752 9.020 100 87 174 1 841110384 7934880 20904752
02:09:56 23946 8192000000 9764137904 10072498176 838420048 9.284 100 87 174 0 872156992 13498816 20238128
02:11:24 17975 8192000000 9806834880 10126098432 873097872 9.631 100 87 173 1 912546944 19576304 19872768
02:12:50 11175 8192000000 9892554192 10225782784 920632112 10.102 100 87 173 0 993048704 53095008 19321584
02:14:06 6492 8192000000 9925730880 10273566720 963255792 10.521 100 92 183 1 1044423840 62536928 18631120
02:15:15 1688 8192000000 9975561104 10331856896 1004517616 10.922 100 97 185 3 1092137456 69311312 18308528
02:16:19 1054 8192000000 9991881488 10357153792 1028945360 11.158 100 94 180 1 1112502272 65801248 17755664
02:17:21 1315 8192000000 10015537104 10386026496 1050260768 11.363 100 92 178 1 1138890576 70611744 18018064
02:18:23 1043 8192000000 10031517008 10409299968 1064472080 11.499 100 92 179 1 1148830576 66172816 18185680
02:19:25 8724 8192000000 10034209856 10414592000 1074946160 11.599 100 88 167 0 1165970944 75558528 15466256
02:20:35 10254 8192000000 10033233296 10420838400 1078642560 11.635 100 66 123 2 1158180656 66815952 12722144
02:21:46 1154 8192000000 10048813296 10436673536 1087748640 11.721 100 85 166 0 1170138848 65723488 16666720
02:22:48 1051 8192000000 10064074304 10452631552 1087577344 11.720 100 86 159 3 1182539024 79557520 15404160
02:23:50 5012 8192000000 10061097808 10447368192 1092883360 11.770 100 84 164 2 1184105472 75406448 15815664
02:24:57 1186 8192000000 10066874240 10456952832 1097573776 11.815 100 96 185 2 1189320352 72136576 19610000
02:25:59 1197 8192000000 10073589728 10460585984 1096536896 11.805 100 96 190 0 1193511344 77275872 19698576
02:27:01 1110 8192000000 10050105984 10438930432 1095562160 11.796 100 94 173 1 1172960144 59635552 17762432
02:28:03 2444 8192000000 10047917072 10438148096 1094317616 11.784 100 77 150 1 1170649984 61695056 14637312
02:29:07 1266 8192000000 10048947712 10439430144 1087222512 11.716 100 82 157 1 1171673568 68385536 16065520
02:30:09 2072 8192000000 10000389664 10387632128 1062917168 11.484 99 84 144 2 1124298416 46168944 15212304
02:31:11 1708 8192000000 10018366496 10409963520 1078449856 11.633 100 73 134 2 1146143280 54588320 13105104
02:32:15 11353 8192000000 10001039536 10388938752 1057180912 11.429 99 54 106 1 1122057488 54252016 10624560
02:33:34 1553 8192000000 9972688912 10343845888 1033986816 11.207 99 77 142 1 1096799840 47954448 14858576
02:34:36 1301 8192000000 10005908592 10382925824 1060404864 11.460 100 95 182 0 1129848384 50027168 19416352
02:35:38 890 8192000000 9989795440 10362036224 1037557264 11.241 100 89 163 1 1112325136 57332096 17435776
02:36:40 17666 8192000000 9982868848 10376089600 1028243936 11.152 99 52 103 1 1131430800 92747200 10439664
02:38:02 24312 8192000000 10013862192 10398142464 1070595264 11.558 100 67 124 2 1148056992 65637744 11823984
02:39:35 11240 8192000000 10038084256 10429493248 1079208800 11.640 100 69 127 1 1165332496 73674352 12449344
02:40:53 7061 8192000000 10076239152 10470088704 1098804464 11.826 100 71 136 2 1197561776 84215984 14541328
02:42:04 5352 8192000000 10114871536 10506883072 1108710496 11.920 100 78 146 0 1236435728 111261648 16463584
02:43:23 6915 8192000000 10156439552 10565513216 1123980384 12.065 100 63 120 0 1274451728 136467328 14004016
02:44:35 2523 8192000000 10176156240 10571689984 1136298496 12.181 100 82 153 0 1284967552 133207568 15461488
02:45:41 2493 8192000000 10234183568 10633539584 1147060896 12.282 100 80 160 1 1342513120 178167920 17284304
02:46:50 807 8192000000 10245999568 10644480000 1159265344 12.396 100 99 194 1 1359346080 179206848 20873888
02:47:54 5269 8192000000 10266662944 10671071232 1169506816 12.492 100 79 154 1 1378132784 191888560 16737408
02:49:05 1227 8192000000 10315620048 10717384704 1191252912 12.695 100 91 179 1 1427246400 215804544 20188944
02:50:11 1317 8192000000 10346147728 10754068480 1197140928 12.750 100 98 192 0 1457896624 239031152 21724544
02:51:15 1234 8192000000 10394526560 10799853568 1199582848 12.772 100 94 187 0 1503111232 281018672 22509712
02:52:18 1362 8192000000 10428782448 10855837696 1202416960 12.799 100 91 182 1 1538738112 315112272 21208880
02:53:23 1832 8192000000 10461784640 10872205312 1197537104 12.753 99 94 185 0 1565376240 348023216 19815920
02:54:29 1030 8192000000 10511399824 10926067712 1208281040 12.853 100 94 182 2 1620886016 390031168 22573808
02:55:33 867 8192000000 10517581728 10950750208 1210297760 12.872 100 98 192 0 1628402096 395204864 22899472
02:56:37 1884 8192000000 10554144528 10964668416 1195355600 12.733 100 87 174 1 1654674128 438716880 20601648
02:57:43 5255 8192000000 10495148720 10964951040 1190233680 12.686 100 59 112 0 1602872224 397304912 15333632
02:58:51 7746 8192000000 10521970736 10966081536 1176242464 12.555 99 59 114 0 1633680496 442274992 15163040
03:00:06 2342 8192000000 10537641312 10986635264 1184964176 12.636 100 74 138 1 1641353488 439049200 17340112
03:01:11 1407 8192000000 10527324752 10982731776 1191680272 12.699 100 87 170 1 1634762624 422348288 20734064
03:02:19 3142 8192000000 10475360592 10966294528 1174205040 12.536 100 66 131 1 1587742784 397032272 16505472
03:03:29 20561 8192000000 10419023488 10926759936 1152838208 12.336 99 44 86 1 1552913104 388136400 11938496
03:04:56 15426 8192000000 10353242448 10855829504 1170915120 12.505 100 42 84 0 1499834160 318477712 10441328
03:06:13 25549 8192000000 10275596560 10735706112 1164988112 12.450 100 31 62 1 1373483376 200294496 8200768
03:07:40 45352 8192000000 10224925024 10716057600 1125415408 12.078 96 24 49 0 1326100976 195223040 5462528
03:09:26 52624 8192000000 10199948624 10696749056 1131441056 12.135 98 15 30 0 1302564176 167765552 3357568
03:11:19 52133 8192000000 10073750848 10505945088 1118489552 12.013 97 30 53 3 1137163712 13874064 4800096
03:13:14 81197 8192000000 10072463936 10539737088 1120541792 12.032 98 16 32 1 1161003984 37318048 3144144
03:15:41 107589 8192000000 10069255360 10538094592 1093071136 11.772 96 15 30 0 1155932096 60020096 2840864
03:18:36 6505 8192000000 10046541776 10456289280 1106894032 11.903 98 68 121 2 1149683920 27444032 15345856
03:19:43 105995 8192000000 10104853744 10588393472 1118094400 12.009 99 28 51 1 1187798480 64168064 5536016
03:22:49 142328 8192000000 10106955232 10541481984 1123769248 12.063 99 33 63 0 1199933856 69262208 6902400
03:26:12 27658 8192000000 10107589248 10557542400 1124654992 12.071 98 23 44 2 1195327200 65283440 5388768
03:27:42 12284 8192000000 10189755600 10615193600 1157066416 12.376 100 73 139 2 1278309312 106330448 14912448
03:29:14 85493 8192000000 10200888448 10676305920 1140433040 12.220 99 16 32 0 1296035632 151437744 4164848
03:31:40 49963 8192000000 10184318736 10654576640 1155178320 12.358 100 17 35 1 1314323712 154890960 4254432
03:33:31 32287 8192000000 10125074848 10598019072 1134376032 12.163 100 28 52 4 1251302944 111079072 5847840
03:35:03 47921 8192000000 10099619856 10543071232 1105932352 11.894 97 31 59 3 1158757168 47147424 5677392
03:36:56 102626 8192000000 10087933888 10520350720 1127962848 12.102 98 28 54 1 1202020560 68193504 5864208
03:39:40 21180 8192000000 10126695456 10563919872 1126166448 12.085 99 42 82 1 1217390480 83316816 7907216
03:41:06 58334 8192000000 10115682128 10554363904 1119761328 12.025 98 20 40 0 1176245664 52525776 3958560
03:43:05 59428 8192000000 10104307488 10552479744 1105814784 11.893 98 44 88 1 1164463568 49772816 8875968
03:45:10 60062 8192000000 10103503648 10573934592 1114956032 11.979 99 30 60 1 1180300800 59605392 5739376
03:48:14 43926 8192000000 10094408048 10552451072 1126122496 12.085 99 30 59 1 1169738208 37148480 6467232
03:49:58 61484 8192000000 10091168576 10550882304 1130792464 12.129 99 62 121 0 1198226640 54036208 13397968
03:52:19 12716 8192000000 10085753264 10527485952 1110321872 11.935 98 25 50 0 1171528720 56344064 4862784
03:53:33 11114 8192000000 10070155440 10516942848 1127680496 12.099 100 30 60 0 1185921760 52419152 5822112
03:54:50 40375 8192000000 10082958384 10525896704 1125584160 12.080 99 21 42 0 1171813328 41422880 4806288
03:56:38 100264 8192000000 10095863408 10529533952 1104854592 11.884 97 19 39 0 1173849008 64635696 4358720
03:59:19 72022 8192000000 10130309920 10615672832 1139700224 12.213 100 16 32 1 1234909280 91598976 3610080
04:02:13 35046 8192000000 10092626096 10520846336 1110581568 11.938 97 37 72 1 1145890400 26929920 8378912
04:03:49 27018 8192000000 10160018352 10600935424 1129196480 12.114 99 36 72 2 1246948784 110279504 7472800
04:05:33 48514 8192000000 10080875952 10553131008 1129542736 12.117 99 16 32 0 1197697360 65059952 3094672
04:07:25 36992 8192000000 10141692688 10596204544 1115099216 11.981 97 52 104 1 1220183088 94761520 10322352
04:09:03 52004 8192000000 10090693360 10556534784 1092487456 11.766 97 30 58 0 1215261152 116916320 5857376
04:10:59 12648 8192000000 10102839872 10519953408 1128455728 12.107 100 58 114 0 1187689504 48271680 10962096
04:12:13 36343 8192000000 10144626336 10595770368 1124684064 12.071 99 46 92 1 1205823008 72206848 8932096
04:14:39 82561 8192000000 10090241984 10558701568 1129341760 12.115 99 24 48 2 1187457088 53546096 4569232
04:17:10 35841 8192000000 10078549296 10551173120 1138423184 12.201 100 8 16 0 1188933568 48948112 1562272
04:18:55 68143 8192000000 10056658128 10538979328 1139846272 12.214 100 14 27 1 1194072096 50822416 3403408
04:21:06 47996 8192000000 10062594768 10517172224 1107136016 11.905 97 17 34 0 1135755296 24774144 3845136
04:22:54 48492 8192000000 10072993616 10522079232 1098450000 11.823 97 13 26 1 1142321536 41350320 2521216
04:24:51 32827 8192000000 10094629424 10535596032 1117217536 12.001 98 36 72 1 1168069744 43090608 7761600
04:26:26 17010 8192000000 10189735424 10625376256 1158414400 12.388 100 43 85 1 1274509744 107274304 8821040
04:27:43 50490 8192000000 10167510656 10667634688 1129907952 12.120 98 22 44 1 1303811872 168893504 5010416
04:29:36 56378 8192000000 10126534720 10631368704 1091242576 11.754 97 15 30 2 1237216208 142567120 3406512
04:31:43 15729 8192000000 10100990880 10526035968 1116976304 11.998 99 53 102 0 1177701792 50183824 10541664
04:33:01 62837 8192000000 10136980336 10605850624 1134909216 12.168 100 41 82 0 1230655760 87578496 8168048
04:35:30 63735 8192000000 10067288416 10544902144 1123494896 12.060 99 7 14 0 1160574096 35682576 1396624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-05-2022 04:39:35 to 25-05-2022 04:39:37.

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          218967
MON$OLDEST_ACTIVE               219791
MON$OLDEST_SNAPSHOT             219791
MON$NEXT_TRANSACTION            219791
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                       2052256
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2768 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2768 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2768 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2768 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Wed May 25 04:37:24 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

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