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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-03 07:37:25
DTS_END                         2023-02-03 07:37:25.0490
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(68:17)
IP                              ::1/49220
TRN_ID                          217980
ATT_ID                          670
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: 9150.90 28362 1098108
customer order: creation 167.98 457 20158
customer order: refuse 13.98 456 1677
order to supplier: creation 44.80 1378 5376
order to supplier: removal 3.57 175871 428
invoice (draft): creation 46.43 3093 5571
invoice (draft): removal 52.51 1095 6301
invoice accept: apply 41.32 29127 4958
invoice accept: cancel 47.04 1288 5645
customer reserve: creation 3855.35 278 462642
customer reserve: removal 4582.99 106 549959
realization accept: apply 82.11 289 9853
realization accept: cancel 84.47 184 10136
payment from customer: creation 58.00 182 6960
payment from customer: removal 5.79 135 695
payment to supplier: creation 48.14 189 5777
payment to supplier: removal 3.39 105 407
service: total inventory turnovers 3.98 4441 478
service: total monetary turnovers 8.07 400 969
service: aggregate perf. data 0.94 14470 113
service: refresh index statistics 0.04 333704 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-02-2023 07:40:34 to 03-02-2023 07:40:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5187
TEST_TIME 2 6917
TEST_TIME 3 6203
TEST_TIME 4 6835
TEST_TIME 5 6603
TEST_TIME 6 6836
TEST_TIME 7 6812
TEST_TIME 8 6486
TEST_TIME 9 6550
TEST_TIME 10 6514
TEST_TIME 11 6731
TEST_TIME 12 6721
TEST_TIME 13 6702
TEST_TIME 14 6773
TEST_TIME 15 6770
TEST_TIME 16 6616
TEST_TIME 17 6493
TEST_TIME 18 6530
TEST_TIME 19 6957
TEST_TIME 20 7355
TEST_TIME 21 7314
TEST_TIME 22 7345
TEST_TIME 23 7407
TEST_TIME 24 7499
TEST_TIME 25 7508
TEST_TIME 26 7595
TEST_TIME 27 7846
TEST_TIME 28 7810
TEST_TIME 29 8192
TEST_TIME 30 8285
TEST_TIME 31 8320
TEST_TIME 32 9349
TEST_TIME 33 9454
TEST_TIME 34 10184
TEST_TIME 35 10396
TEST_TIME 36 10580
TEST_TIME 37 10631
TEST_TIME 38 10510
TEST_TIME 39 10368
TEST_TIME 40 10260
TEST_TIME 41 10281
TEST_TIME 42 10097
TEST_TIME 43 9981
TEST_TIME 44 9989
TEST_TIME 45 9947
TEST_TIME 46 9793
TEST_TIME 47 9667
TEST_TIME 48 9689
TEST_TIME 49 9600
TEST_TIME 50 9548
TEST_TIME 51 9449
TEST_TIME 52 9469
TEST_TIME 53 9564
TEST_TIME 54 9680
TEST_TIME 55 9791
TEST_TIME 56 9731
TEST_TIME 57 9680
TEST_TIME 58 9601
TEST_TIME 59 9657
TEST_TIME 60 9596
TEST_TIME 61 9671
TEST_TIME 62 9666
TEST_TIME 63 9619
TEST_TIME 64 9666
TEST_TIME 65 9736
TEST_TIME 66 9755
TEST_TIME 67 9783
TEST_TIME 68 9658
TEST_TIME 69 9566
TEST_TIME 70 9607
TEST_TIME 71 9527
TEST_TIME 72 9464
TEST_TIME 73 9402
TEST_TIME 74 9361
TEST_TIME 75 9482
TEST_TIME 76 9455
TEST_TIME 77 9391
TEST_TIME 78 9308
TEST_TIME 79 9392
TEST_TIME 80 9376
TEST_TIME 81 9336
TEST_TIME 82 9337
TEST_TIME 83 9339
TEST_TIME 84 9326
TEST_TIME 85 9326
TEST_TIME 86 9327
TEST_TIME 87 9450
TEST_TIME 88 9425
TEST_TIME 89 9401
TEST_TIME 90 9449
TEST_TIME 91 9459
TEST_TIME 92 9491
TEST_TIME 93 9492
TEST_TIME 94 9476
TEST_TIME 95 9393
TEST_TIME 96 9364
TEST_TIME 97 9378
TEST_TIME 98 9353
TEST_TIME 99 9353
TEST_TIME 100 9329
TEST_TIME 101 9299
TEST_TIME 102 9257
TEST_TIME 103 9222
TEST_TIME 104 9186
TEST_TIME 105 9162
TEST_TIME 106 9151
TEST_TIME 107 9117
TEST_TIME 108 9115
TEST_TIME 109 9121
TEST_TIME 110 9170
TEST_TIME 111 9182
TEST_TIME 112 9145
TEST_TIME 113 9147
TEST_TIME 114 9168
TEST_TIME 115 9200
TEST_TIME 116 9238
TEST_TIME 117 9225
TEST_TIME 118 9214
TEST_TIME 119 9242
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-02-2023 07:40:38 to 03-02-2023 07:40:41.

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 1525294 1525294 0 0.00 0 19651 16 0 0
doc_list_biud 1525286 1525286 0 0.00 0 37087 44 0 0
sp_add_invoice_to_stock 5045 4958 87 1.72 49 218712 29127 0 87
sp_cancel_adding_invoice 5645 5645 0 0.00 6 79194 1288 0 0
sp_cancel_client_order 1677 1677 0 0.00 7 10597 457 0 0
sp_cancel_customer_reserve 549959 549959 0 0.00 1 37099 106 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 3804 136 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 3168 105 0 0
sp_cancel_supplier_invoice 6307 6301 6 0.09 3 33559 1096 0 6
sp_cancel_supplier_order 428 428 0 0.00 11 1833630 175871 0 0
sp_cancel_write_off 10136 10136 0 0.00 2 7060 184 0 0
sp_client_order 20158 20158 0 0.00 6 22962 457 0 0
sp_customer_reserve 462642 462642 0 0.00 0 162899 278 0 0
sp_fill_shopping_cart 31087 31087 0 0.00 1 11710 76 0 0
sp_fill_shopping_cart_clo_res 462612 462612 0 0.00 0 21857 18 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 34110 254 0 0
sp_kill_cost_storno 16883 16883 0 0.00 0 8202 85 0 0
sp_kill_qstorno_ret_qs2qd 1116783 1116783 0 0.00 0 37028 47 0 0
sp_kill_qty_storno 588917 588917 0 0.00 0 37087 107 0 0
sp_lock_selected_doc 20508 20508 0 0.00 0 9302 10 0 0
sp_make_cost_storno 27509 27509 0 0.00 0 16648 80 0 0
sp_multiply_rows_for_pdistr 27508 27508 0 0.00 0 16626 41 0 0
sp_multiply_rows_for_qdistr 20158 20158 0 0.00 0 22952 176 0 0
sp_pay_from_customer 6960 6960 0 0.00 5 11792 182 0 0
sp_pay_to_supplier 5777 5777 0 0.00 6 16657 190 0 0
sp_payment_common 12737 12737 0 0.00 2 6336 47 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 1 10589 373 0 0
sp_qd_handle_on_invoice_upd_sts 10563 10563 0 0.00 0 18412 548 0 0
sp_qd_handle_on_reserve_upd_sts 9853 9853 0 0.00 0 11595 70 0 0
sp_reserve_write_off 9853 9853 0 0.00 8 12070 290 0 0
sp_supplier_invoice 5571 5571 0 0.00 8 110204 3094 0 0
sp_supplier_order 5376 5376 0 0.00 6 41094 1378 0 0
srv_aggregate_perf_data 113 113 0 0.00 2365 208118 14471 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 676 201392 36653 0 0
srv_find_qd_qs_mism 1050198 1050198 0 0.00 0 19650 13 0 0
srv_make_invnt_saldo 478 478 0 0.00 55 116793 4442 0 0
srv_make_money_saldo 969 969 0 0.00 1 18065 400 0 0
srv_recalc_idx_stat 5 5 0 0.00 199510 537601 333704 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4966 4966 0 0.00 1 13942 972 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 6177 512 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 830 23 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 4970 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1992 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 256 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2408 38 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 69806 7132 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 3855 24 0 0
t$perf-norm:sp_client_order 20160 20160 0 0.00 0 6690 36 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 710 16 0 0
t$perf-norm:sp_pay_from_customer 6960 6960 0 0.00 0 3435 19 0 0
t$perf-norm:sp_pay_to_supplier 5777 5777 0 0.00 0 7172 21 0 0
t$perf-norm:sp_reserve_write_off 9853 9853 0 0.00 0 10184 25 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 4932 33 0 0
t$perf-norm:sp_supplier_order 5376 5376 0 0.00 0 4039 34 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 505 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 3165 55 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 3766 35 0 0
t$perf-norm:srv_make_money_saldo 969 969 0 0.00 0 2056 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 196 71 0 0
v_add_invoice_to_stock 10000 9826 174 1.74 0 6745 28 0 174
v_all_customers 36640 36640 0 0.00 0 178 4 0 0
v_all_suppliers 30622 30622 0 0.00 0 140 4 0 0
v_all_wares 40306 40306 0 0.00 0 132 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3707 24 0 0
v_cancel_client_order 3354 3354 0 0.00 0 4342 14 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3797 17 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 1 4674 16 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 605 10 0 0
v_cancel_supplier_invoice 860 848 12 1.39 0 479 22 0 12
v_cancel_supplier_order 856 856 0 0.00 0 3144 15 0 0
v_cancel_write_off 2050 2050 0 0.00 0 6990 31 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 631 5 0 0
v_max_id_clo_res 946 946 0 0.00 0 2829 14 0 0
v_max_id_ord_sup 5561 5561 0 0.00 0 3827 6 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 1538 9 0 0
v_max_non_paid_realizn 3693 3693 0 0.00 0 637 7 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 1413 8 0 0
v_min_id_clo_res 946 946 0 0.00 0 5562 15 0 0
v_min_id_ord_sup 5561 5561 0 0.00 0 5216 11 0 0
v_min_non_paid_invoice 5777 5777 0 0.00 0 5033 16 0 0
v_min_non_paid_realizn 6960 6960 0 0.00 0 6328 22 0 0
v_our_firm 10200 10200 0 0.00 0 60 4 0 0
v_reserve_write_off 19704 19704 0 0.00 0 5202 13 0 0
x_lock_depdocs_on_canc_invoice 11522 11522 0 0.00 0 10719 128 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 3299 359 0 0
x_make_qstorno_1000_1200 5376 5376 0 0.00 2 41061 1217 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 4 108083 2887 0 0
x_make_qstorno_2100_3300 462640 462640 0 0.00 0 162893 247 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-02-2023 07:40:42 to 03-02-2023 07:40:46.

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
05:07:29 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:30 9416 8192000000 9708840256 10006499328 797957008 8.876 100 93 186 0 828305920 7580192 22768720
05:09:45 23676 8192000000 9750383648 10058166272 830975104 9.209 100 85 170 0 862978432 11586976 20416352
05:11:22 18744 8192000000 9790974176 10108977152 863134000 9.531 100 84 168 1 901049168 18417904 19497264
05:12:44 8417 8192000000 9844519328 10174205952 899819456 9.897 100 79 156 0 955267424 38079984 17367984
05:13:56 7617 8192000000 9910864304 10247004160 940473664 10.298 100 86 168 1 1025154224 66791520 17889040
05:15:06 3745 8192000000 9939334512 10288435200 970070336 10.587 100 79 158 2 1055488304 69187264 16230704
05:16:12 1677 8192000000 9978955552 10336804864 999053584 10.869 100 88 174 0 1094559184 77698592 17807008
05:17:17 2321 8192000000 10000251344 10364743680 1024725232 11.118 100 85 163 2 1114917728 73832608 16359888
05:18:20 1069 8192000000 10031416608 10401787904 1046309552 11.325 100 97 186 0 1148382256 83321648 18751056
05:19:22 1107 8192000000 10031200336 10406027264 1057949664 11.437 100 97 190 2 1148498832 71221360 19327808
05:20:25 7391 8192000000 10031827760 10405994496 1065271984 11.507 100 88 164 0 1148907216 68256880 15378352
05:21:35 1039 8192000000 10035040064 10416340992 1071929584 11.571 100 89 170 0 1154262032 65610672 16721776
05:22:36 9028 8192000000 10025826336 10415710208 1078442048 11.633 100 79 157 2 1159885344 66313104 15130192
05:23:53 4870 8192000000 10035509888 10431492096 1084562048 11.691 100 66 131 0 1156284768 58927984 12794736
05:25:00 1024 8192000000 10075822816 10465124352 1089922304 11.742 100 94 186 1 1201399056 91881504 19595248
05:26:03 1186 8192000000 10047637072 10437455872 1091349360 11.755 100 92 177 0 1165546544 56318768 17878416
05:27:04 1096 8192000000 10084663600 10475606016 1094030304 11.781 100 96 187 0 1205208352 91446496 19731552
05:28:06 1100 8192000000 10054725632 10438242304 1096401360 11.803 100 92 158 2 1175111984 63221280 15489344
05:29:09 5965 8192000000 10060980208 10456432640 1098180208 11.820 100 72 140 0 1178356784 66057792 14118784
05:30:16 1301 8192000000 10041378496 10432409600 1090918240 11.751 100 76 145 0 1161298464 55845952 14534272
05:31:18 5053 8192000000 10038882512 10423332864 1082900896 11.675 100 86 160 1 1155797824 57368464 15528464
05:32:23 2105 8192000000 10015120512 10402054144 1075653424 11.606 100 79 142 5 1135398592 43253856 16491312
05:33:27 1507 8192000000 10004350608 10380697600 1052321232 11.383 99 94 177 1 1125152208 54094880 18736096
05:34:29 965 8192000000 9995854896 10368856064 1049693568 11.358 100 86 165 1 1114477200 47303472 17480160
05:35:31 5557 8192000000 9986932784 10372378624 1042531264 11.289 99 72 129 1 1106528384 51038448 12958672
05:36:37 6633 8192000000 9985677872 10371969024 1043363200 11.297 100 65 104 3 1111835968 57744016 10728752
05:37:49 46284 8192000000 9983453696 10361528320 1034234032 11.209 99 65 130 0 1094208448 47165920 12808496
05:39:41 1525 8192000000 10044689024 10423541760 1067114256 11.525 100 91 178 2 1165143824 79428704 18600864
05:40:49 15195 8192000000 10038655264 10417479680 1064250336 11.497 99 78 144 0 1152286080 73917968 14117776
05:42:08 1453 8192000000 10095190928 10480807936 1095874752 11.798 100 94 177 1 1210511632 95358624 19278256
05:43:14 4240 8192000000 10104843568 10492510208 1098607600 11.824 99 73 139 1 1222021184 109652064 13761520
05:44:22 1574 8192000000 10134683056 10534928384 1122643824 12.052 100 90 174 2 1245605296 104673632 18287840
05:45:27 1364 8192000000 10163167952 10552094720 1136088704 12.179 100 89 175 3 1285567040 130964592 18513744
05:46:36 2986 8192000000 10211857952 10608762880 1140063872 12.216 100 82 164 1 1320040032 162440880 17535280
05:47:44 10587 8192000000 10219542720 10631192576 1146753488 12.279 100 73 146 0 1338165024 175334672 16076864
05:49:00 2523 8192000000 10243481584 10643263488 1164466016 12.445 100 76 151 1 1357970432 177257840 16246576
05:50:06 2146 8192000000 10301731344 10697506816 1178602272 12.577 100 91 173 2 1410217904 212875232 18740400
05:51:15 1580 8192000000 10316788048 10732781568 1194681440 12.727 100 74 147 0 1433308720 221481952 17145328
05:52:20 813 8192000000 10376964784 10775945216 1210486128 12.874 100 96 187 4 1485687504 252585152 22616224
05:53:25 822 8192000000 10407993216 10809589760 1201715328 12.792 100 99 192 0 1517207968 293057792 22434848
05:54:32 2558 8192000000 10452756176 10863747072 1198894256 12.766 100 86 164 0 1568092416 349883888 19314272
05:55:38 1129 8192000000 10480822688 10919628800 1197940928 12.757 100 92 177 4 1593518928 373740208 21837792
05:56:44 812 8192000000 10453244896 10910371840 1203414832 12.808 100 97 195 1 1561942800 335105328 23422640
05:57:49 794 8192000000 10485055120 10890579968 1207214464 12.843 100 90 173 1 1583644896 355594368 20836064
05:58:56 797 8192000000 10503638384 10919976960 1211021088 12.879 100 99 196 1 1608397472 374252352 23124032
06:00:03 2532 8192000000 10537240320 10952474624 1210399056 12.873 100 94 181 2 1638864656 406465536 22000064
06:01:09 1187 8192000000 10558601712 10986930176 1209602320 12.865 100 97 191 0 1660861024 428007408 23251296
06:02:15 664 8192000000 10561716864 10966044672 1190527536 12.688 100 98 195 1 1664153184 449782752 23842896
06:03:18 1805 8192000000 10618971248 11039817728 1201655936 12.792 100 96 186 1 1724281968 499732016 22894016
06:04:22 7946 8192000000 10578341296 11035926528 1165165120 12.452 99 53 104 1 1680222848 501016128 14041600
06:05:36 4424 8192000000 10622235824 11060162560 1177279088 12.565 100 64 125 2 1727489616 534512672 15697856
06:06:47 6640 8192000000 10569935632 11083116544 1184026704 12.628 100 44 88 1 1674787808 479196544 11564560
06:08:05 9712 8192000000 10534571600 11019603968 1169217504 12.490 100 46 90 0 1662435264 481001360 12216400
06:09:17 32023 8192000000 10410620512 10946097152 1152783376 12.336 99 40 78 0 1531994592 368873360 10337856
06:10:57 19563 8192000000 10239694464 10800238592 1128131456 12.104 99 26 49 1 1333272832 198699648 6441728
06:12:46 49721 8192000000 10140120496 10649505792 1128066736 12.103 99 14 27 0 1231002144 100014912 2920496
06:14:42 45001 8192000000 10068222080 10492948480 1132952224 12.149 100 38 70 0 1165461296 25393952 7115120
06:16:28 49570 8192000000 10060332608 10494156800 1110066352 11.933 98 15 29 0 1146496192 33864272 2565568
06:18:21 96765 8192000000 10053409168 10481016832 1122504896 12.051 99 24 46 0 1169096496 41533488 5058112
06:21:05 201387 8192000000 10100391376 10532716544 1107881712 11.912 98 37 72 1 1175665792 59997296 7786784
06:25:31 17817 8192000000 10144503008 10575478784 1127357840 12.096 97 50 92 2 1234807984 96751888 10698256
06:26:54 26356 8192000000 10229560432 10677665792 1164794752 12.448 100 47 94 0 1321944752 146986448 10163552
06:28:28 151603 8192000000 10188325312 10687385600 1163743184 12.438 100 23 46 0 1375318176 206029712 5545280
06:33:22 26621 8192000000 10077854528 10525798400 1136642496 12.184 99 40 73 3 1170291744 27057728 6591520
06:35:01 40326 8192000000 10126895200 10554585088 1119014576 12.018 97 49 96 1 1190304912 61084688 10205648
06:36:48 69105 8192000000 10135813056 10584281088 1126399264 12.087 98 26 52 0 1207542624 75336656 5806704
06:39:03 88137 8192000000 10130108288 10588590080 1128978544 12.112 98 20 40 0 1207403312 73593088 4831680
06:41:33 79127 8192000000 10102188976 10597445632 1115773120 11.987 97 17 33 0 1206317296 87211600 3332576
06:43:53 60937 8192000000 10078673216 10547384320 1077818224 11.627 95 22 44 1 1114349472 31637952 4893296
06:45:58 100842 8192000000 10070753344 10523164672 1090224432 11.745 97 33 63 1 1137024016 40371104 6428480
06:48:40 68532 8192000000 10126301776 10561437696 1120401216 12.031 99 42 84 1 1215065936 85659792 9004928
06:50:51 52168 8192000000 10159473584 10613850112 1120648096 12.033 99 31 61 0 1249708816 121854960 7205760
06:52:57 62609 8192000000 10089122080 10560348160 1131461072 12.135 100 25 50 1 1208122608 71131120 5530416
06:55:05 55080 8192000000 10076982096 10537230336 1100498208 11.842 97 10 20 0 1187347728 84782976 2066544
06:57:02 55131 8192000000 10055722848 10516451328 1080576944 11.653 96 17 34 0 1119672608 35758144 3337520
06:58:58 75115 8192000000 10078728944 10512285696 1110319824 11.935 98 27 50 1 1162161552 46676384 5165344
07:01:17 31210 8192000000 10157112000 10591227904 1129039536 12.112 99 43 84 0 1217768256 80067312 8661408
07:02:50 36215 8192000000 10189577328 10617131008 1126933584 12.092 99 37 72 0 1277678144 142976752 7767808
07:04:27 38850 8192000000 10068232880 10492620800 1087744224 11.721 97 31 62 0 1122421216 28579216 6097776
07:06:08 17677 8192000000 10089846128 10513018880 1098253008 11.821 98 43 85 1 1176163248 69190592 8719648
07:07:30 46405 8192000000 10135565040 10596921344 1123367872 12.059 100 54 105 1 1222611584 89012320 10231392
07:09:43 48669 8192000000 10092272416 10566295552 1117069712 11.999 99 26 52 0 1183491472 61143296 5278464
07:11:33 142699 8192000000 10102159216 10578472960 1121920336 12.045 99 29 58 0 1183642720 55222656 6499728
07:15:03 20210 8192000000 10077854448 10511380480 1117582768 12.004 99 34 66 0 1158261216 34342320 6336128
07:16:28 57546 8192000000 10075201024 10543345664 1104383024 11.879 97 15 30 0 1142477648 35107200 2987424
07:18:26 35551 8192000000 10048212304 10514722816 1132666256 12.146 100 11 23 0 1178407312 43532336 2208720
07:20:03 56273 8192000000 10066358880 10541420544 1132475568 12.145 100 11 22 0 1169299280 34730208 2093504
07:22:05 41637 8192000000 10056723424 10536345600 1124813424 12.072 99 13 26 0 1147780672 20526768 2440480
07:23:48 37478 8192000000 10061610560 10509877248 1120385264 12.031 98 26 52 0 1149378336 24026096 4966976
07:25:31 11277 8192000000 10127404816 10572828672 1143518176 12.249 100 49 98 0 1216837184 63614192 9704816
07:26:44 29972 8192000000 10136675824 10575187968 1129415920 12.116 98 39 75 0 1199298896 62776320 7106656
07:28:14 45776 8192000000 10077358448 10553921536 1121304256 12.039 98 22 44 0 1174400080 48627728 4468096
07:30:03 45812 8192000000 10143713376 10600951808 1146902336 12.280 99 34 68 1 1229349856 75528880 6918640
07:31:54 74801 8192000000 10120265168 10573258752 1116662384 11.995 97 17 34 0 1188259392 68072704 3524304
07:34:09 22236 8192000000 10094995376 10524839936 1125736704 12.081 99 52 104 0 1166586016 30719280 10130032
07:35:38 25944 8192000000 10137223856 10588905472 1133250480 12.152 99 68 134 0 1211987872 65633280 13104112
07:37:04 78169 8192000000 8908795440 9107329024 80852640 0.977 8 6 12 0 102068400 20023840 1191920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 03-02-2023 07:40:48 to 03-02-2023 07:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-02-2023 07:41:06 to 03-02-2023 07:41:08.

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          219198
MON$OLDEST_ACTIVE               219199
MON$OLDEST_SNAPSHOT             219199
MON$NEXT_TRANSACTION            219199
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                       2062304
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.02.23 07:41:09. 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
2023-02-03 07:41:11 No severe PSQL-related problems occured
03.02.23 07:41:13. End of report.