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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-09 19:38:42
DTS_END                         2023-03-09 19:38:42.4580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56620
TRN_ID                          243865
ATT_ID                          715
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: 9332.58 22864 1119909
customer order: creation 173.65 344 20838
customer order: refuse 14.93 326 1792
order to supplier: creation 46.42 1262 5570
order to supplier: removal 3.33 86400 399
invoice (draft): creation 46.54 3494 5585
invoice (draft): removal 51.99 677 6239
invoice accept: apply 42.64 18285 5117
invoice accept: cancel 47.24 776 5669
customer reserve: creation 3962.41 181 475489
customer reserve: removal 4643.75 47 557250
realization accept: apply 83.24 184 9989
realization accept: cancel 85.99 105 10319
payment from customer: creation 59.64 120 7157
payment from customer: removal 5.72 77 686
payment to supplier: creation 49.50 116 5940
payment to supplier: removal 3.48 101 417
service: total inventory turnovers 4.64 1042 557
service: total monetary turnovers 6.58 27 790
service: aggregate perf. data 0.85 18700 102
service: refresh index statistics 0.03 325009 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-03-2023 19:40:49 to 09-03-2023 19:40:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3402
TEST_TIME 2 3220
TEST_TIME 3 3613
TEST_TIME 4 3130
TEST_TIME 5 4016
TEST_TIME 6 3854
TEST_TIME 7 4393
TEST_TIME 8 5678
TEST_TIME 9 5654
TEST_TIME 10 6305
TEST_TIME 11 6730
TEST_TIME 12 7162
TEST_TIME 13 7171
TEST_TIME 14 7190
TEST_TIME 15 7579
TEST_TIME 16 7654
TEST_TIME 17 7731
TEST_TIME 18 7971
TEST_TIME 19 8069
TEST_TIME 20 8264
TEST_TIME 21 8059
TEST_TIME 22 8313
TEST_TIME 23 8505
TEST_TIME 24 9022
TEST_TIME 25 9127
TEST_TIME 26 9114
TEST_TIME 27 9496
TEST_TIME 28 9496
TEST_TIME 29 9637
TEST_TIME 30 9585
TEST_TIME 31 9698
TEST_TIME 32 10070
TEST_TIME 33 10435
TEST_TIME 34 10250
TEST_TIME 35 9965
TEST_TIME 36 10243
TEST_TIME 37 10181
TEST_TIME 38 10043
TEST_TIME 39 9992
TEST_TIME 40 9929
TEST_TIME 41 9756
TEST_TIME 42 9674
TEST_TIME 43 9604
TEST_TIME 44 9602
TEST_TIME 45 9495
TEST_TIME 46 9666
TEST_TIME 47 9507
TEST_TIME 48 9491
TEST_TIME 49 9382
TEST_TIME 50 9305
TEST_TIME 51 9293
TEST_TIME 52 9270
TEST_TIME 53 9186
TEST_TIME 54 9205
TEST_TIME 55 9161
TEST_TIME 56 9245
TEST_TIME 57 9204
TEST_TIME 58 9205
TEST_TIME 59 9157
TEST_TIME 60 9236
TEST_TIME 61 9203
TEST_TIME 62 9234
TEST_TIME 63 9385
TEST_TIME 64 9384
TEST_TIME 65 9337
TEST_TIME 66 9509
TEST_TIME 67 9620
TEST_TIME 68 9586
TEST_TIME 69 9580
TEST_TIME 70 9599
TEST_TIME 71 9569
TEST_TIME 72 9546
TEST_TIME 73 9535
TEST_TIME 74 9456
TEST_TIME 75 9478
TEST_TIME 76 9442
TEST_TIME 77 9374
TEST_TIME 78 9347
TEST_TIME 79 9264
TEST_TIME 80 9256
TEST_TIME 81 9287
TEST_TIME 82 9267
TEST_TIME 83 9294
TEST_TIME 84 9263
TEST_TIME 85 9321
TEST_TIME 86 9289
TEST_TIME 87 9360
TEST_TIME 88 9354
TEST_TIME 89 9345
TEST_TIME 90 9447
TEST_TIME 91 9560
TEST_TIME 92 9553
TEST_TIME 93 9588
TEST_TIME 94 9506
TEST_TIME 95 9434
TEST_TIME 96 9440
TEST_TIME 97 9406
TEST_TIME 98 9355
TEST_TIME 99 9400
TEST_TIME 100 9375
TEST_TIME 101 9351
TEST_TIME 102 9330
TEST_TIME 103 9380
TEST_TIME 104 9348
TEST_TIME 105 9395
TEST_TIME 106 9390
TEST_TIME 107 9364
TEST_TIME 108 9431
TEST_TIME 109 9407
TEST_TIME 110 9465
TEST_TIME 111 9476
TEST_TIME 112 9448
TEST_TIME 113 9424
TEST_TIME 114 9413
TEST_TIME 115 9419
TEST_TIME 116 9417
TEST_TIME 117 9401
TEST_TIME 118 9413
TEST_TIME 119 9370
TEST_TIME 120 9337

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-03-2023 19:40:53 to 09-03-2023 19:40:57.

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 1559804 1559804 0 0.00 0 26884 6 0 0
doc_list_biud 1559802 1559802 0 0.00 0 41636 22 0 0
sp_add_invoice_to_stock 5325 5117 208 3.90 42 173072 18286 0 208
sp_cancel_adding_invoice 5669 5669 0 0.00 4 73007 777 0 0
sp_cancel_client_order 1792 1792 0 0.00 4 17467 327 0 0
sp_cancel_customer_reserve 557250 557250 0 0.00 1 23484 48 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 4739 77 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 9851 102 0 0
sp_cancel_supplier_invoice 6256 6239 17 0.27 1 20962 678 0 17
sp_cancel_supplier_order 399 399 0 0.00 7 775751 86401 0 0
sp_cancel_write_off 10319 10319 0 0.00 1 22875 105 0 0
sp_client_order 20838 20838 0 0.00 3 23945 344 0 0
sp_customer_reserve 475489 475489 0 0.00 0 86797 181 0 0
sp_fill_shopping_cart 31985 31985 0 0.00 1 17393 39 0 0
sp_fill_shopping_cart_clo_res 475485 475485 0 0.00 0 23855 14 0 0
sp_get_clo_for_invoice 5112 5112 0 0.00 0 35837 335 0 0
sp_kill_cost_storno 17091 17091 0 0.00 0 9820 50 0 0
sp_kill_qstorno_ret_qs2qd 1131457 1131457 0 0.00 0 23471 26 0 0
sp_kill_qty_storno 596769 596769 0 0.00 0 41633 57 0 0
sp_lock_selected_doc 20873 20873 0 0.00 0 6748 5 0 0
sp_make_cost_storno 28198 28198 0 0.00 0 21437 61 0 0
sp_multiply_rows_for_pdistr 28198 28198 0 0.00 0 11362 27 0 0
sp_multiply_rows_for_qdistr 20838 20838 0 0.00 0 23930 167 0 0
sp_pay_from_customer 7157 7157 0 0.00 4 20607 120 0 0
sp_pay_to_supplier 5940 5940 0 0.00 4 18621 116 0 0
sp_payment_common 13097 13097 0 0.00 2 10964 33 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 7249 289 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 41629 319 0 0
sp_qd_handle_on_reserve_upd_sts 9989 9989 0 0.00 0 12990 53 0 0
sp_reserve_write_off 9989 9989 0 0.00 6 30733 184 0 0
sp_supplier_invoice 5585 5585 0 0.00 6 88953 3495 0 0
sp_supplier_order 5570 5570 0 0.00 5 44295 1262 0 0
srv_aggregate_perf_data 102 102 0 0.00 2947 285075 18701 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2113 148865 47204 0 0
srv_find_qd_qs_mism 1071190 1071190 0 0.00 0 26884 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 38 56954 1042 0 0
srv_make_money_saldo 790 790 0 0.00 0 1907 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 83985 571887 325010 0 0
t$perf-abend:sp_add_invoice_to_stock 208 208 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 0 12874 160 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 2893 91 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 9893 25 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 5038 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 12885 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 291 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 521 12 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 21435 1685 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 3515 13 0 0
t$perf-norm:sp_client_order 20838 20838 0 0.00 0 11153 19 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 994 6 0 0
t$perf-norm:sp_pay_from_customer 7157 7157 0 0.00 0 12909 13 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 6706 13 0 0
t$perf-norm:sp_reserve_write_off 9989 9989 0 0.00 0 8207 14 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 8603 23 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 10540 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 2353 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 183 3 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1639 24 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 6791 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10640 10224 416 3.90 0 19236 21 0 416
v_all_customers 37802 37802 0 0.00 0 29 1 0 0
v_all_suppliers 30616 30616 0 0.00 0 33 1 0 0
v_all_wares 41676 41676 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 4456 13 0 0
v_cancel_client_order 3584 3584 0 0.00 0 17148 12 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2649 7 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 998 4 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 3352 8 0 0
v_cancel_supplier_invoice 838 804 34 4.05 0 20102 39 0 34
v_cancel_supplier_order 798 798 0 0.00 0 973 5 0 0
v_cancel_write_off 2054 2054 0 0.00 0 4791 17 0 0
v_max_id_clo_ord 5569 5569 0 0.00 0 449 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 4022 12 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 5829 4 0 0
v_max_non_paid_invoice 1779 1779 0 0.00 0 3124 4 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 3954 7 0 0
v_min_id_clo_ord 5569 5569 0 0.00 0 17377 15 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 7830 24 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 5860 8 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 10959 23 0 0
v_min_non_paid_realizn 7157 7157 0 0.00 0 9184 26 0 0
v_our_firm 10506 10506 0 0.00 0 28 1 0 0
v_reserve_write_off 19978 19978 0 0.00 0 20549 7 0 0
x_lock_depdocs_on_canc_invoice 11506 11506 0 0.00 0 12434 121 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7726 343 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 1 44284 1183 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 3 88921 3356 0 0
x_make_qstorno_2100_3300 475489 475489 0 0.00 0 86775 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2023 19:40:58 to 09-03-2023 19:41:02.

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
17:08:46 13 8192000000 8820616144 8839598080 20973840 0.255 8 4 5 0 21205872 92768 139264
17:09:47 19447 8192000000 9887774608 10196586496 967431616 10.562 100 89 178 0 997401072 5976624 23992832
17:11:12 25929 8192000000 9943985520 10264027136 1024832912 11.119 100 88 176 0 1055767344 7981520 22952912
17:12:41 15296 8192000000 9976241360 10305163264 1055467360 11.413 100 82 163 0 1084891568 9033776 20390432
17:14:03 10207 8192000000 10032176736 10380439552 1119582176 12.023 100 71 142 0 1146525712 9891408 17052128
17:15:18 7974 8192000000 10083059728 10440163328 1165588416 12.456 100 85 170 1 1197114496 12058064 19468016
17:16:28 4220 8192000000 10111529264 10480533504 1201557744 12.791 100 79 157 1 1232694384 13797056 17339584
17:17:33 2642 8192000000 10151762144 10527830016 1239765680 13.144 100 88 174 0 1273586384 14494128 19326576
17:18:37 1733 8192000000 10189156800 10570891264 1265210064 13.378 100 91 181 0 1296954016 12270656 19473296
17:19:41 910 8192000000 10208650320 10593222656 1292694976 13.629 100 94 188 0 1326830352 12321744 21813632
17:20:43 33048 8192000000 10192963872 10608779264 1307653232 13.765 100 39 78 0 1322902064 5899440 9349392
17:22:19 8065 8192000000 10204070112 10617651200 1315612528 13.837 100 22 44 0 1327116240 6859872 4643840
17:23:33 17484 8192000000 10209081312 10629029888 1324296832 13.916 100 13 26 1 1333310960 6299344 2714784
17:24:54 73707 8192000000 10215429760 10641997824 1330130384 13.968 100 28 54 2 1341972464 5636464 6205616
17:27:10 5677 8192000000 10223520960 10646491136 1335107968 14.013 100 30 59 0 1347545632 5735312 6702352
17:28:16 4145 8192000000 10212806544 10629857280 1320851248 13.884 100 17 33 2 1330072096 5825776 3395072
17:29:21 14200 8192000000 10179125344 10595250176 1276136896 13.478 98 14 29 0 1284890384 5398544 3354944
17:30:39 19057 8192000000 10158443968 10562834432 1217539520 12.939 95 44 88 1 1233787504 5649840 10598144
17:31:59 6419 8192000000 10158938688 10560978944 1246169936 13.203 98 31 59 2 1259052960 6480112 6402912
17:33:07 7032 8192000000 10167707520 10566746112 1260750000 13.337 100 18 36 0 1270241760 5331840 4159920
17:34:16 6316 8192000000 10184376064 10595495936 1292606800 13.628 100 20 40 0 1302433504 5490304 4336400
17:35:22 43581 8192000000 10176605968 10595549184 1286289616 13.570 99 32 64 1 1299903872 5728096 7886160
17:37:08 6028 8192000000 10203886992 10614669312 1306567792 13.755 100 22 44 0 1317633312 5938672 5126848
17:38:14 10312 8192000000 10216984400 10635317248 1324335616 13.916 100 20 41 0 1332669136 4463872 3869648
17:39:31 35494 8192000000 10250549408 10670374912 1347771536 14.127 100 31 61 1 1361832640 7341312 6719792
17:41:10 120401 8192000000 10266958080 10696642560 1358805696 14.227 99 39 78 0 1378882688 10334080 9742912
17:44:26 8535 8192000000 10290980672 10714087424 1383768000 14.450 100 38 75 0 1404922528 12127232 9027296
17:45:39 7583 8192000000 10302591760 10723110912 1384768544 14.459 100 53 106 0 1414709184 16514016 13426624
17:46:51 7161 8192000000 10305512656 10729627648 1385675456 14.467 100 53 104 1 1411807216 13840496 12291264
17:48:06 10840 8192000000 10312425360 10740301824 1387697776 14.485 99 57 112 1 1416629168 15204608 13726784
17:49:21 2867 8192000000 10322570736 10738737152 1391454960 14.519 100 73 146 1 1424750592 16755664 16539968
17:50:27 3467 8192000000 10304553648 10729353216 1373081008 14.355 99 67 136 0 1406428208 17346688 16000512
17:51:35 8305 8192000000 10319742240 10741846016 1388689936 14.494 100 64 128 0 1423415952 19142096 15583920
17:52:50 3629 8192000000 10318873728 10736500736 1383029744 14.444 100 72 141 1 1423974784 22988112 17956928
17:53:57 3308 8192000000 10319677120 10726699008 1379385024 14.411 100 77 152 1 1420121488 23103824 17632640
17:55:05 2112 8192000000 10316093920 10732023808 1372196976 14.347 99 69 138 0 1411985264 22863408 16924880
17:56:14 3864 8192000000 10315150144 10719322112 1368606544 14.315 100 84 167 1 1413831680 24346848 20878288
17:57:22 13538 8192000000 10303291424 10729861120 1375777856 14.379 100 40 80 2 1414418944 27880336 10760752
17:58:45 27498 8192000000 10283610784 10707075072 1329279312 13.961 97 24 48 0 1362768144 27210928 6277904
18:00:16 5874 8192000000 10313392544 10730536960 1364490032 14.278 100 58 114 3 1414562768 35134576 14938160
18:01:32 15879 8192000000 10272094544 10699116544 1327139936 13.941 99 38 76 0 1378893648 41720224 10033488
18:02:56 17944 8192000000 10280166832 10718085120 1351162384 14.158 100 18 36 0 1383974896 28143632 4668880
18:04:26 24096 8192000000 10253383520 10672861184 1308195664 13.770 99 24 48 1 1341672560 27594992 5881904
18:05:52 17833 8192000000 10257128640 10686058496 1322780064 13.902 99 22 42 1 1353038416 24673296 5585056
18:07:13 37114 8192000000 10258994624 10687578112 1329994912 13.967 99 12 24 0 1356071728 22874960 3201856
18:08:51 33663 8192000000 10280201392 10710638592 1359451296 14.232 100 23 46 1 1387598352 22520752 5626304
18:10:49 59036 8192000000 10296033248 10746642432 1376491168 14.385 100 9 18 0 1406965680 28300640 2173872
18:12:57 74818 8192000000 10292084128 10738200576 1377994464 14.399 100 36 72 0 1402457200 15829040 8633696
18:15:16 89293 8192000000 10294772192 10740498432 1359813744 14.236 98 31 62 0 1375871088 9643136 6414208
18:17:46 17767 8192000000 10301438800 10742341632 1387408544 14.483 100 46 89 0 1407983616 10041840 10533232
18:19:05 148864 8192000000 10321408176 10782253056 1405350624 14.643 100 35 68 1 1424616480 11307536 7958320
18:22:34 43418 8192000000 10315815952 10777657344 1404391184 14.634 100 12 24 0 1427766640 20458960 2916496
18:24:19 74443 8192000000 10310248496 10765004800 1397756368 14.575 100 22 46 2 1408729792 6157360 4816064
18:26:48 53771 8192000000 10296105904 10753777664 1382354592 14.438 99 24 47 0 1398141744 10416464 5370688
18:28:44 43981 8192000000 10287920128 10749349888 1379823744 14.415 99 23 46 0 1401876736 17131664 4921328
18:30:47 45453 8192000000 10292403776 10756259840 1390823968 14.513 100 10 20 0 1409160128 15801136 2535024
18:32:44 63328 8192000000 10278324016 10741219328 1380742432 14.423 99 17 34 0 1395702304 11085392 3874480
18:34:55 124836 8192000000 10291895904 10748506112 1358055776 14.220 97 9 18 0 1377687824 17780064 1851984
18:38:00 70517 8192000000 10288283984 10742534144 1352836832 14.173 97 26 52 0 1368462160 10093600 5531728
18:40:11 79085 8192000000 10304851952 10760118272 1350548256 14.152 97 20 40 0 1373118048 18441952 4127840
18:42:38 36762 8192000000 10289853200 10731794432 1343670576 14.090 97 33 66 0 1364240864 13456768 7113520
18:44:15 57535 8192000000 10276044720 10748866560 1340758544 14.064 98 14 28 0 1371732464 27760000 3213920
18:46:46 41441 8192000000 10264501120 10687262720 1250908208 13.247 92 40 80 0 1270941072 11564864 8468000
18:48:32 82021 8192000000 10244620688 10691035136 1319559104 13.873 98 11 22 1 1340298704 18160320 2579280
18:51:18 53502 8192000000 10261574512 10704687104 1336791376 14.028 99 26 50 2 1350514208 7923232 5799600
18:53:17 28718 8192000000 10241760352 10684932096 1326650832 13.937 99 23 47 0 1339598816 7994272 4953712
18:54:49 52084 8192000000 10248938880 10698559488 1348490752 14.134 100 15 30 0 1358841984 6894048 3457184
18:56:41 53942 8192000000 10252440320 10710708224 1360908016 14.246 100 19 37 0 1375457520 10096688 4452816
18:59:03 53960 8192000000 10252691376 10715963392 1359242112 14.231 100 15 28 1 1372251664 9399824 3609728
19:00:58 19697 8192000000 10275021616 10719023104 1351326800 14.159 99 32 65 2 1368845152 10541024 6977328
19:02:18 24923 8192000000 10276425424 10723278848 1366724512 14.298 100 22 44 0 1379413312 7859104 4829696
19:03:43 38703 8192000000 10289088176 10735333376 1359845392 14.236 99 22 44 0 1381388816 16263168 5280256
19:05:25 89821 8192000000 10288486224 10758803456 1339214928 14.050 97 8 16 0 1362073088 21316048 1542112
19:07:57 62270 8192000000 10300430736 10768961536 1381962848 14.434 100 14 28 0 1407104912 21611008 3531056
19:10:36 105856 8192000000 10315465984 10769620992 1399219424 14.588 100 16 32 0 1414738944 12527968 2991552
19:13:24 60554 8192000000 10298650080 10760183808 1392037568 14.524 99 32 64 0 1409724368 10856832 6829968
19:15:33 63999 8192000000 10294646288 10754510848 1370199248 14.329 98 9 18 0 1385022960 12910208 1913504
19:17:44 103085 8192000000 10296617216 10752000000 1374071856 14.364 98 6 12 0 1385286176 10006640 1207680
19:20:30 67888 8192000000 10284063504 10747998208 1347376832 14.124 97 19 38 0 1368198208 16603968 4217408
19:22:38 32846 8192000000 10313753824 10763841536 1392460176 14.528 100 28 56 0 1408741888 10271472 6010240
19:25:00 27813 8192000000 10302221424 10755403776 1377636560 14.395 99 16 32 1 1393588336 12721552 3230224
19:26:30 59741 8192000000 10293018448 10726023168 1284928000 13.558 92 32 62 0 1300282432 7862656 7491776
19:28:34 71387 8192000000 10269514240 10716286976 1293285776 13.634 94 23 44 1 1315942272 18240368 4416128
19:30:57 94730 8192000000 10270971568 10724098048 1353711136 14.181 99 20 40 0 1370711200 12108928 4891136
19:33:42 86618 8192000000 10277183280 10750242816 1350036048 14.148 98 9 18 0 1374012368 21808368 2167952
19:36:09 68222 8192000000 10245450768 10703400960 1295304720 13.653 95 17 34 1 1312915072 13372096 4238256
19:38:17 35963 8192000000 9525895296 9862045696 671822784 7.579 50 6 12 0 680332272 7252208 1257280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-03-2023 19:41:04 to 09-03-2023 19:41:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 208 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-03-2023 19:41:20 to 09-03-2023 19:41:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244859
MON$OLDEST_ACTIVE               244860
MON$OLDEST_SNAPSHOT             244860
MON$NEXT_TRANSACTION            244860
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2033632
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1243: 
:1244: mscs-test-161.budzdorov.ru    Thu Mar  9 19:39:15 2023
:1245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1246: 
--- end of diff output ---

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

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