Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.2022 13: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 14594804 kB
MemAvailable 30147176 kB
Buffers 0 kB
Cached 17128632 kB
SwapCached 4384 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 2022-12-08 13:37:34
DTS_END                         2022-12-08 13:37:21.3140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55670
TRN_ID                          228719
ATT_ID                          259
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9194.07 21614 1103288
customer order: creation 183.61 443 22033
customer order: refuse 15.53 387 1864
order to supplier: creation 47.46 1301 5695
order to supplier: removal 3.41 158704 409
invoice (draft): creation 48.23 3280 5787
invoice (draft): removal 50.93 945 6112
invoice accept: apply 44.09 24833 5291
invoice accept: cancel 45.70 1142 5484
customer reserve: creation 4003.88 242 480465
customer reserve: removal 4439.39 97 532727
realization accept: apply 87.93 266 10551
realization accept: cancel 88.64 163 10637
payment from customer: creation 61.63 167 7395
payment from customer: removal 5.92 140 710
payment to supplier: creation 51.77 180 6212
payment to supplier: removal 3.50 128 420
service: total inventory turnovers 4.06 3904 487
service: total monetary turnovers 7.43 359 891
service: aggregate perf. data 0.95 16905 114
service: refresh index statistics 0.03 218701 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2022 13:39:09 to 08-12-2022 13:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7570
TEST_TIME 2 5773
TEST_TIME 3 5340
TEST_TIME 4 5105
TEST_TIME 5 5254
TEST_TIME 6 5901
TEST_TIME 7 5811
TEST_TIME 8 6238
TEST_TIME 9 6305
TEST_TIME 10 6744
TEST_TIME 11 6845
TEST_TIME 12 6840
TEST_TIME 13 7057
TEST_TIME 14 7030
TEST_TIME 15 7281
TEST_TIME 16 7346
TEST_TIME 17 7428
TEST_TIME 18 7382
TEST_TIME 19 7441
TEST_TIME 20 7554
TEST_TIME 21 7642
TEST_TIME 22 7906
TEST_TIME 23 8507
TEST_TIME 24 8531
TEST_TIME 25 8557
TEST_TIME 26 8678
TEST_TIME 27 8771
TEST_TIME 28 9103
TEST_TIME 29 9476
TEST_TIME 30 9726
TEST_TIME 31 9686
TEST_TIME 32 10132
TEST_TIME 33 10416
TEST_TIME 34 10611
TEST_TIME 35 10738
TEST_TIME 36 10830
TEST_TIME 37 10678
TEST_TIME 38 10517
TEST_TIME 39 10599
TEST_TIME 40 10502
TEST_TIME 41 10429
TEST_TIME 42 10321
TEST_TIME 43 10275
TEST_TIME 44 10234
TEST_TIME 45 10364
TEST_TIME 46 10332
TEST_TIME 47 10154
TEST_TIME 48 10022
TEST_TIME 49 9935
TEST_TIME 50 9877
TEST_TIME 51 9779
TEST_TIME 52 9718
TEST_TIME 53 9711
TEST_TIME 54 9681
TEST_TIME 55 9685
TEST_TIME 56 9643
TEST_TIME 57 9627
TEST_TIME 58 9714
TEST_TIME 59 9653
TEST_TIME 60 9655
TEST_TIME 61 9573
TEST_TIME 62 9621
TEST_TIME 63 9603
TEST_TIME 64 9550
TEST_TIME 65 9546
TEST_TIME 66 9641
TEST_TIME 67 9656
TEST_TIME 68 9660
TEST_TIME 69 9648
TEST_TIME 70 9631
TEST_TIME 71 9672
TEST_TIME 72 9660
TEST_TIME 73 9649
TEST_TIME 74 9649
TEST_TIME 75 9670
TEST_TIME 76 9615
TEST_TIME 77 9558
TEST_TIME 78 9467
TEST_TIME 79 9432
TEST_TIME 80 9417
TEST_TIME 81 9381
TEST_TIME 82 9369
TEST_TIME 83 9353
TEST_TIME 84 9281
TEST_TIME 85 9271
TEST_TIME 86 9261
TEST_TIME 87 9275
TEST_TIME 88 9243
TEST_TIME 89 9242
TEST_TIME 90 9246
TEST_TIME 91 9306
TEST_TIME 92 9308
TEST_TIME 93 9286
TEST_TIME 94 9382
TEST_TIME 95 9430
TEST_TIME 96 9490
TEST_TIME 97 9462
TEST_TIME 98 9430
TEST_TIME 99 9357
TEST_TIME 100 9435
TEST_TIME 101 9400
TEST_TIME 102 9379
TEST_TIME 103 9368
TEST_TIME 104 9355
TEST_TIME 105 9313
TEST_TIME 106 9333
TEST_TIME 107 9328
TEST_TIME 108 9318
TEST_TIME 109 9292
TEST_TIME 110 9244
TEST_TIME 111 9247
TEST_TIME 112 9241
TEST_TIME 113 9362
TEST_TIME 114 9368
TEST_TIME 115 9363
TEST_TIME 116 9329
TEST_TIME 117 9300
TEST_TIME 118 9232
TEST_TIME 119 9248
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2022 13:39:13 to 08-12-2022 13:39:16.

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 1545801 1545801 0 0.00 0 24086 13 0 0
doc_list_biud 1545794 1545794 0 0.00 0 95107 39 0 0
sp_add_invoice_to_stock 5437 5291 146 2.68 36 207657 24834 0 146
sp_cancel_adding_invoice 5484 5484 0 0.00 6 65318 1143 0 0
sp_cancel_client_order 1864 1864 0 0.00 6 10423 387 0 0
sp_cancel_customer_reserve 532727 532727 0 0.00 1 33122 97 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 4 13374 140 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2108 128 0 0
sp_cancel_supplier_invoice 6127 6112 15 0.24 3 95128 945 0 15
sp_cancel_supplier_order 409 409 0 0.00 17 1421788 158704 0 0
sp_cancel_write_off 10637 10637 0 0.00 2 21926 163 0 0
sp_client_order 22033 22033 0 0.00 5 35740 443 0 0
sp_customer_reserve 480465 480465 0 0.00 0 130868 243 0 0
sp_fill_shopping_cart 33513 33513 0 0.00 1 17744 75 0 0
sp_fill_shopping_cart_clo_res 480435 480435 0 0.00 0 15545 15 0 0
sp_get_clo_for_invoice 5256 5256 0 0.00 0 29278 238 0 0
sp_kill_cost_storno 17251 17251 0 0.00 0 23032 70 0 0
sp_kill_qstorno_ret_qs2qd 1082612 1082612 0 0.00 0 95101 43 0 0
sp_kill_qty_storno 573039 573039 0 0.00 0 95106 97 0 0
sp_lock_selected_doc 21878 21878 0 0.00 0 10300 7 0 0
sp_make_cost_storno 29414 29414 0 0.00 0 13014 74 0 0
sp_multiply_rows_for_pdistr 29414 29414 0 0.00 0 9024 38 0 0
sp_multiply_rows_for_qdistr 22033 22033 0 0.00 0 22189 168 0 0
sp_pay_from_customer 7395 7395 0 0.00 5 12939 167 0 0
sp_pay_to_supplier 6212 6212 0 0.00 6 12732 180 0 0
sp_payment_common 13607 13607 0 0.00 2 12577 47 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 10409 314 0 0
sp_qd_handle_on_invoice_upd_sts 10740 10740 0 0.00 0 26829 489 0 0
sp_qd_handle_on_reserve_upd_sts 10550 10550 0 0.00 0 18564 62 0 0
sp_reserve_write_off 10551 10551 0 0.00 9 18748 267 0 0
sp_supplier_invoice 5787 5787 0 0.00 10 101904 3280 0 0
sp_supplier_order 5695 5695 0 0.00 7 33000 1302 0 0
srv_aggregate_perf_data 114 114 0 0.00 2899 149884 16905 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 825 125850 33990 0 0
srv_find_qd_qs_mism 1052057 1052057 0 0.00 0 24085 10 0 0
srv_make_invnt_saldo 487 487 0 0.00 73 77424 3905 0 0
srv_make_money_saldo 891 891 0 0.00 1 10398 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 43894 333626 218702 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5293 5293 0 0.00 0 11506 835 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 1 6009 356 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 1537 26 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 3853 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 2547 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 311 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 971 33 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 111289 6167 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 4482 23 0 0
t$perf-norm:sp_client_order 22034 22034 0 0.00 0 10373 36 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 4102 17 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 17291 20 0 0
t$perf-norm:sp_pay_to_supplier 6212 6212 0 0.00 0 8800 20 0 0
t$perf-norm:sp_reserve_write_off 10551 10551 0 0.00 0 12504 23 0 0
t$perf-norm:sp_supplier_invoice 5787 5787 0 0.00 0 4668 29 0 0
t$perf-norm:sp_supplier_order 5695 5695 0 0.00 0 7345 30 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 645 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 117 5 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 353 9 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 784 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 18 88 41 0 0
v_add_invoice_to_stock 10798 10506 292 2.70 0 3703 23 0 292
v_all_customers 40198 40198 0 0.00 0 204 4 0 0
v_all_suppliers 31984 31984 0 0.00 0 119 4 0 0
v_all_wares 44066 44066 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 3188 19 0 0
v_cancel_client_order 3728 3728 0 0.00 0 1407 9 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 302 9 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 1234 12 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1968 18 0 0
v_cancel_supplier_invoice 874 844 30 3.43 0 3175 26 0 30
v_cancel_supplier_order 818 818 0 0.00 0 1439 16 0 0
v_cancel_write_off 2164 2164 0 0.00 0 402 23 0 0
v_max_id_clo_ord 5695 5695 0 0.00 0 137 5 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 603 9 0 0
v_max_id_ord_sup 5784 5784 0 0.00 0 5490 8 0 0
v_max_non_paid_invoice 1698 1698 0 0.00 0 149 7 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 4643 9 0 0
v_min_id_clo_ord 5695 5695 0 0.00 0 2878 9 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 4113 18 0 0
v_min_id_ord_sup 5784 5784 0 0.00 0 4874 9 0 0
v_min_non_paid_invoice 6212 6212 0 0.00 0 4494 15 0 0
v_min_non_paid_realizn 7395 7395 0 0.00 0 7150 19 0 0
v_our_firm 10998 10998 0 0.00 0 73 5 0 0
v_reserve_write_off 21100 21100 0 0.00 0 3419 10 0 0
x_lock_depdocs_on_canc_invoice 11174 11174 0 0.00 0 23312 113 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3398 319 0 0
x_make_qstorno_1000_1200 5695 5695 0 0.00 3 32986 1153 0 0
x_make_qstorno_1200_2000 5787 5787 0 0.00 5 101867 3077 0 0
x_make_qstorno_2100_3300 480465 480465 0 0.00 0 130866 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2022 13:39:17 to 08-12-2022 13:39:21.

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
11:07:25 3 8192000000 8798040656 8809009152 4167568 0.050 2 1 2 0 4266960 63616 35776
11:08:26 23869 8192000000 9726933440 10028564480 802669424 8.923 100 87 174 0 832248880 8032128 21547328
11:09:53 15336 8192000000 9758463248 10066472960 834784672 9.247 100 86 171 0 867502560 12735744 19982144
11:11:18 16293 8192000000 9802484080 10119741440 868237008 9.582 100 89 178 0 908591888 19392128 20962752
11:12:37 13680 8192000000 9864388800 10195894272 907952800 9.977 100 81 160 1 969002960 42595488 18454672
11:13:57 5075 8192000000 9900442704 10243682304 955880832 10.449 100 86 168 1 1013249760 39467760 17901168
11:15:05 2929 8192000000 9960920672 10313031680 996796800 10.847 100 86 169 0 1073916272 60070304 17049168
11:16:10 1347 8192000000 10026545104 10387677184 1018541280 11.058 100 92 181 0 1142063824 105611376 17911168
11:17:14 1037 8192000000 10002261984 10370093056 1038127328 11.247 100 87 160 2 1127446000 74113424 15205248
11:18:23 948 8192000000 10027268096 10402889728 1056795824 11.426 100 96 192 0 1148689648 73061888 18831936
11:19:25 1059 8192000000 10020524704 10398507008 1068991904 11.542 100 93 182 0 1149112736 61938864 18181968
11:20:28 1295 8192000000 10065905424 10449793024 1073550800 11.586 100 95 184 0 1191281536 99769536 17961200
11:21:30 3640 8192000000 10032488400 10426146816 1079100544 11.639 100 69 133 0 1155640752 61964736 14575472
11:22:35 938 8192000000 10048348784 10439086080 1085166608 11.697 100 74 143 2 1168410416 68379536 14864272
11:23:37 861 8192000000 10033621680 10426609664 1087098320 11.715 100 73 127 2 1155970464 55930240 12941904
11:24:38 747 8192000000 10062822688 10450038784 1092897136 11.770 100 98 191 4 1182955920 70277904 19780880
11:25:40 2588 8192000000 10063764800 10459049984 1094859232 11.789 100 69 134 0 1187416768 79582064 12975472
11:26:44 1214 8192000000 10045424960 10433384448 1092732512 11.769 100 91 178 0 1169199200 57538448 18928240
11:27:46 1341 8192000000 10074979152 10470744064 1101318272 11.850 100 69 136 0 1198784912 83857728 13608912
11:28:49 1231 8192000000 10068005312 10461175808 1096720000 11.807 100 97 186 1 1188548928 72562208 19266720
11:29:51 1169 8192000000 10054318576 10443997184 1085020160 11.695 100 93 177 1 1180620112 77636256 17963696
11:30:53 1110 8192000000 10013035744 10406551552 1071600416 11.567 100 84 159 1 1139471200 50448816 17421968
11:31:56 1187 8192000000 9991303856 10371690496 1045805520 11.320 99 72 127 2 1113495248 54034288 13655440
11:32:58 1616 8192000000 9970341872 10343596032 1034156320 11.208 100 88 166 3 1096937552 46008848 16772384
11:34:00 964 8192000000 9981182672 10357784576 1036616224 11.232 100 84 157 4 1105572224 52470032 16485968
11:35:02 1759 8192000000 9985744496 10358009856 1022091680 11.092 99 86 165 1 1108638544 70660208 15886656
11:36:04 1034 8192000000 9986385632 10360893440 1041822592 11.282 100 87 166 1 1107187952 48967616 16397744
11:37:06 1006 8192000000 9989666544 10362859520 1045712224 11.320 100 93 171 4 1115971216 52251328 18007664
11:38:11 1206 8192000000 9987436576 10367680512 1056542432 11.423 100 89 165 1 1113585056 38284992 18757632
11:39:22 9720 8192000000 10023850416 10404196352 1075283056 11.603 100 72 136 2 1135446832 44824240 15339536
11:40:37 2820 8192000000 10048844336 10437259264 1088248416 11.726 100 70 133 1 1166706912 64837088 13621408
11:41:45 20017 8192000000 10076338016 10473193472 1095611888 11.796 100 54 105 0 1192585552 85990992 10982672
11:43:14 8844 8192000000 10140043200 10542907392 1116173216 11.991 100 69 136 0 1247224560 117170224 13881120
11:44:27 823 8192000000 10150319104 10538430464 1123105664 12.056 100 87 164 3 1263165392 123065504 16994224
11:45:32 1400 8192000000 10158054720 10554482688 1132992704 12.150 100 94 182 2 1276756608 124408240 19355664
11:46:36 1907 8192000000 10172479472 10577309696 1141795712 12.232 100 91 180 1 1286518432 124391872 20330848
11:47:41 1109 8192000000 10216245456 10621505536 1152601392 12.334 100 96 188 0 1332757280 159067552 21088336
11:48:46 1737 8192000000 10238610272 10651492352 1164945904 12.450 100 79 147 2 1350728880 169090640 16692336
11:49:51 1269 8192000000 10267755168 10671321088 1174471072 12.539 100 84 160 1 1385875984 193843536 17561376
11:50:53 906 8192000000 10312903728 10718109696 1182801760 12.616 100 94 188 1 1425714928 222364144 20549024
11:52:02 1718 8192000000 10347889840 10757656576 1188430176 12.669 100 95 186 0 1454863376 245517520 20915680
11:53:08 1299 8192000000 10355850160 10772164608 1188224496 12.667 100 84 160 2 1470110592 263194224 18691872
11:54:14 928 8192000000 10403834032 10814222336 1195406384 12.734 100 98 190 1 1511591072 294109968 22074720
11:55:19 818 8192000000 10415889648 10820235264 1182277760 12.611 100 95 182 0 1522197328 319594080 20325488
11:56:23 1125 8192000000 10438732752 10855030784 1172478816 12.520 100 89 175 0 1543098176 349719312 20900048
11:57:27 2343 8192000000 10463438528 10867589120 1173422528 12.529 100 84 168 0 1570642784 377225328 19994928
11:58:33 1330 8192000000 10470654624 10896007168 1162715536 12.429 99 96 185 0 1577681264 392319680 22646048
11:59:38 928 8192000000 10465133280 10934116352 1175488880 12.548 100 98 190 1 1567280256 368337728 23453648
12:00:40 8085 8192000000 10446179696 10903425024 1181944976 12.608 100 42 84 0 1551861872 358916784 11000112
12:01:54 6160 8192000000 10437187424 10874560512 1163394160 12.435 99 47 94 0 1537991856 362449584 12148112
12:03:02 15898 8192000000 10444644544 10901872640 1157372912 12.379 99 38 74 1 1555556128 388005120 10178096
12:04:36 9329 8192000000 10452749280 10912534528 1156904176 12.374 99 49 94 0 1549789008 380705008 12179824
12:05:56 5900 8192000000 10401380576 10884759552 1167020400 12.469 100 45 89 0 1519080448 341057840 11002208
12:07:03 28597 8192000000 10346894256 10835472384 1142570256 12.240 99 27 54 0 1440165744 290628624 6966864
12:08:35 58462 8192000000 10287458928 10788651008 1145583776 12.268 98 20 40 0 1405201312 255023392 4594144
12:10:34 41918 8192000000 10147339312 10638082048 1132115952 12.141 98 26 53 1 1249934560 112232320 5586288
12:12:17 13193 8192000000 10160085248 10612035584 1148645808 12.297 100 20 40 0 1271439216 118592480 4200928
12:14:02 18221 8192000000 10058183376 10500059136 1132351328 12.144 99 54 106 2 1155349808 10338256 12660224
12:15:23 125850 8192000000 10064643312 10574884864 1103141520 11.867 97 16 31 0 1166455392 59915488 3398384
12:18:55 30243 8192000000 10053292096 10496933888 1128220256 12.105 100 19 34 0 1158792192 27450320 3121616
12:20:29 47561 8192000000 10097009408 10540224512 1112620848 11.957 99 21 43 0 1151396912 34282096 4493968
12:22:18 107258 8192000000 10048519952 10510397440 1096078736 11.800 97 10 19 0 1162752752 64640592 2033424
12:25:05 61188 8192000000 10065254256 10516406272 1109006512 11.923 98 41 78 0 1150454640 32158768 9289360
12:27:08 74742 8192000000 10098590096 10546176000 1109310864 11.926 98 51 97 0 1177147184 58226672 9609648
12:29:24 1944 8192000000 10118973280 10547355648 1136930544 12.187 100 51 95 0 1213504288 66222496 10351248
12:30:27 12712 8192000000 10124639888 10560888832 1123399264 12.059 99 40 74 1 1231960016 99707728 8853024
12:31:41 41192 8192000000 10159475696 10597842944 1140684288 12.222 100 44 79 1 1240284528 90002720 9597520
12:33:29 18621 8192000000 10176100272 10643542016 1146169904 12.274 100 33 61 0 1264896384 111477600 7248880
12:35:08 103769 8192000000 10111113056 10575855616 1120532912 12.032 98 12 25 0 1202550496 79321984 2695600
12:37:52 49746 8192000000 10066479440 10526101504 1129776704 12.119 100 9 18 0 1165318368 33692704 1848960
12:39:45 49250 8192000000 10060715568 10564435968 1114038352 11.971 98 7 13 1 1148729568 33797056 894160
12:41:43 30030 8192000000 10098135184 10546581504 1130543408 12.126 100 44 82 0 1196947600 57270736 9133456
12:43:17 67608 8192000000 10094337136 10581651456 1136470576 12.182 99 29 56 1 1214039040 71834912 5733552
12:45:39 9031 8192000000 10113474256 10546003968 1133794560 12.157 100 74 139 2 1197933712 49652976 14486176
12:46:48 7427 8192000000 10097338496 10525913088 1131045888 12.131 100 44 86 1 1193500128 53590928 8863312
12:47:59 87872 8192000000 10093240656 10561978368 1133899200 12.158 100 32 61 0 1207988240 68320160 5768880
12:50:27 29686 8192000000 10077316128 10511241216 1093860560 11.779 97 33 66 0 1152147696 51759792 6527344
12:52:00 66714 8192000000 10093558864 10574245888 1079655088 11.644 96 34 67 0 1181404608 94620752 7128768
12:54:07 51772 8192000000 10056045072 10494595072 1089326784 11.736 97 23 42 0 1130951920 37384752 4240384
12:56:01 924 8192000000 10079106512 10512830464 1121570800 12.042 100 56 111 0 1176258096 42732736 11954560
12:57:03 66154 8192000000 10091744576 10574266368 1118906032 12.017 99 16 32 0 1214353072 91970704 3476336
12:59:10 46609 8192000000 10079111456 10554970112 1126793440 12.091 100 25 46 0 1177814752 46330720 4690592
13:00:59 59481 8192000000 10095280528 10543058944 1107789584 11.911 98 12 24 0 1167363328 57124704 2449040
13:02:58 21924 8192000000 10123614592 10555715584 1137855536 12.195 100 38 75 0 1217562016 71205840 8500640
13:04:26 17757 8192000000 10169266320 10628665344 1152296352 12.331 100 38 76 0 1266495632 105823408 8375872
13:05:48 22148 8192000000 10190373600 10628239360 1121779200 12.044 97 43 81 0 1260907056 129978192 9149664
13:07:16 16057 8192000000 10191003728 10623242240 1117355936 12.002 97 36 72 0 1260995392 135877008 7762448
13:08:34 29589 8192000000 10168137840 10610237440 1144661088 12.259 100 34 68 0 1269324832 117413536 7250208
13:10:06 28971 8192000000 10200135040 10640334848 1134815568 12.167 99 23 46 0 1274284832 134285312 5183952
13:11:35 48560 8192000000 10159451504 10619097088 1099249888 11.831 96 42 84 0 1237450384 128977456 9223040
13:13:34 81786 8192000000 10158379280 10591940608 1133765280 12.157 99 36 67 1 1241765840 100954144 7046416
13:15:58 84024 8192000000 10175801360 10626646016 1139827024 12.214 100 38 76 0 1269681200 121363280 8490896
13:18:37 92689 8192000000 10106454880 10625822720 1126786480 12.091 99 14 27 0 1222801840 93086064 2929296
13:21:32 66009 8192000000 10107380128 10544549888 1105084448 11.886 97 27 51 1 1157295984 46220944 5990592
13:23:41 56157 8192000000 10087124320 10575970304 1117514976 12.004 98 22 44 0 1168461408 46446208 4500224
13:25:43 44567 8192000000 10088540896 10535596032 1091186368 11.754 97 27 54 0 1151661360 55071312 5403680
13:27:30 46169 8192000000 10144736928 10596605952 1117322512 12.002 99 24 46 0 1215218096 92827456 5068128
13:29:43 87112 8192000000 10108531872 10625802240 1125724128 12.081 99 21 41 0 1268999728 138476896 4798704
13:32:10 68647 8192000000 10098081408 10540044288 1068021968 11.533 95 29 58 0 1115942752 42128304 5792480
13:34:22 119485 8192000000 10096341808 10564837376 1089226672 11.735 97 38 75 0 1195282768 98369424 7686672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-12-2022 13:39:23 to 08-12-2022 13:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2022 13:39:41 to 08-12-2022 13:39:43.

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          228748
MON$OLDEST_ACTIVE               230285
MON$OLDEST_SNAPSHOT             230285
MON$NEXT_TRANSACTION            230285
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                       2088288
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2882 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2882 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2882 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2882 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 ---
:136: 
:137: mscs-test-161.budzdorov.ru    Thu Dec  8 13:37:38 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
--- end of diff output ---

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

08.12.22 13:39:44. 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-12-08 13:39:46 No severe PSQL-related problems occured
08.12.22 13:39:48. End of report.