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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15128252 kB
MemAvailable 30115736 kB
Buffers 0 kB
Cached 16571040 kB
SwapCached 1716 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 2022-12-06 22:38:32
DTS_END                         2022-12-06 22:38:32.7900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45956
TRN_ID                          250514
ATT_ID                          678
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: 9424.57 20398 1130948
customer order: creation 182.62 352 21914
customer order: refuse 15.43 360 1851
order to supplier: creation 49.52 1214 5942
order to supplier: removal 3.64 93792 437
invoice (draft): creation 49.79 3356 5975
invoice (draft): removal 53.08 863 6370
invoice accept: apply 45.08 18530 5410
invoice accept: cancel 47.39 788 5687
customer reserve: creation 4056.55 188 486786
customer reserve: removal 4616.33 57 553960
realization accept: apply 87.80 179 10536
realization accept: cancel 79.51 115 9541
payment from customer: creation 63.23 101 7587
payment from customer: removal 6.36 91 763
payment to supplier: creation 51.78 126 6213
payment to supplier: removal 3.53 70 424
service: total inventory turnovers 5.13 1086 615
service: total monetary turnovers 6.80 29 816
service: aggregate perf. data 0.98 15485 117
service: refresh index statistics 0.03 271179 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2768
TEST_TIME 2 3758
TEST_TIME 3 4074
TEST_TIME 4 4276
TEST_TIME 5 5376
TEST_TIME 6 5338
TEST_TIME 7 5705
TEST_TIME 8 5846
TEST_TIME 9 5879
TEST_TIME 10 5914
TEST_TIME 11 6224
TEST_TIME 12 6551
TEST_TIME 13 6520
TEST_TIME 14 6935
TEST_TIME 15 7006
TEST_TIME 16 7164
TEST_TIME 17 7299
TEST_TIME 18 7663
TEST_TIME 19 7866
TEST_TIME 20 8175
TEST_TIME 21 8296
TEST_TIME 22 8717
TEST_TIME 23 9385
TEST_TIME 24 9548
TEST_TIME 25 10448
TEST_TIME 26 10781
TEST_TIME 27 10682
TEST_TIME 28 10908
TEST_TIME 29 10812
TEST_TIME 30 10649
TEST_TIME 31 10624
TEST_TIME 32 10567
TEST_TIME 33 10382
TEST_TIME 34 10183
TEST_TIME 35 10140
TEST_TIME 36 9984
TEST_TIME 37 9900
TEST_TIME 38 9859
TEST_TIME 39 9878
TEST_TIME 40 9855
TEST_TIME 41 9806
TEST_TIME 42 9685
TEST_TIME 43 9749
TEST_TIME 44 9707
TEST_TIME 45 9731
TEST_TIME 46 9632
TEST_TIME 47 9560
TEST_TIME 48 9538
TEST_TIME 49 9458
TEST_TIME 50 9530
TEST_TIME 51 9545
TEST_TIME 52 9491
TEST_TIME 53 9518
TEST_TIME 54 9691
TEST_TIME 55 9630
TEST_TIME 56 9662
TEST_TIME 57 9623
TEST_TIME 58 9634
TEST_TIME 59 9849
TEST_TIME 60 9782
TEST_TIME 61 9686
TEST_TIME 62 9587
TEST_TIME 63 9566
TEST_TIME 64 9475
TEST_TIME 65 9576
TEST_TIME 66 9511
TEST_TIME 67 9421
TEST_TIME 68 9403
TEST_TIME 69 9498
TEST_TIME 70 9492
TEST_TIME 71 9455
TEST_TIME 72 9444
TEST_TIME 73 9401
TEST_TIME 74 9557
TEST_TIME 75 9542
TEST_TIME 76 9556
TEST_TIME 77 9565
TEST_TIME 78 9551
TEST_TIME 79 9608
TEST_TIME 80 9659
TEST_TIME 81 9699
TEST_TIME 82 9607
TEST_TIME 83 9556
TEST_TIME 84 9495
TEST_TIME 85 9480
TEST_TIME 86 9512
TEST_TIME 87 9457
TEST_TIME 88 9434
TEST_TIME 89 9399
TEST_TIME 90 9403
TEST_TIME 91 9392
TEST_TIME 92 9420
TEST_TIME 93 9455
TEST_TIME 94 9434
TEST_TIME 95 9469
TEST_TIME 96 9496
TEST_TIME 97 9479
TEST_TIME 98 9489
TEST_TIME 99 9563
TEST_TIME 100 9554
TEST_TIME 101 9557
TEST_TIME 102 9620
TEST_TIME 103 9605
TEST_TIME 104 9633
TEST_TIME 105 9583
TEST_TIME 106 9539
TEST_TIME 107 9484
TEST_TIME 108 9465
TEST_TIME 109 9506
TEST_TIME 110 9473
TEST_TIME 111 9433
TEST_TIME 112 9412
TEST_TIME 113 9445
TEST_TIME 114 9420
TEST_TIME 115 9396
TEST_TIME 116 9457
TEST_TIME 117 9459
TEST_TIME 118 9458
TEST_TIME 119 9466
TEST_TIME 120 9430

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2022 22:39:34 to 06-12-2022 22:39:37.

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 1581383 1581383 0 0.00 0 16475 6 0 0
doc_list_biud 1581383 1581383 0 0.00 0 60082 26 0 0
sp_add_invoice_to_stock 5539 5410 129 2.32 20 184271 18530 0 129
sp_cancel_adding_invoice 5687 5687 0 0.00 4 70622 789 0 0
sp_cancel_client_order 1851 1851 0 0.00 5 14272 361 0 0
sp_cancel_customer_reserve 553960 553960 0 0.00 0 60085 57 0 0
sp_cancel_pay_from_customer 763 763 0 0.00 2 6761 92 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 2505 71 0 0
sp_cancel_supplier_invoice 6386 6370 16 0.25 1 13345 864 0 16
sp_cancel_supplier_order 437 437 0 0.00 5 1161181 93792 0 0
sp_cancel_write_off 9541 9541 0 0.00 1 13271 116 0 0
sp_client_order 21914 21914 0 0.00 3 50173 353 0 0
sp_customer_reserve 486786 486786 0 0.00 0 84791 189 0 0
sp_fill_shopping_cart 33822 33822 0 0.00 1 16081 41 0 0
sp_fill_shopping_cart_clo_res 486777 486777 0 0.00 0 17246 15 0 0
sp_get_clo_for_invoice 5393 5393 0 0.00 0 34454 297 0 0
sp_kill_cost_storno 16415 16415 0 0.00 0 9535 54 0 0
sp_kill_qstorno_ret_qs2qd 1124268 1124268 0 0.00 0 54517 32 0 0
sp_kill_qty_storno 593774 593774 0 0.00 0 60081 69 0 0
sp_lock_selected_doc 22060 22060 0 0.00 0 11039 8 0 0
sp_make_cost_storno 29728 29728 0 0.00 0 16469 51 0 0
sp_multiply_rows_for_pdistr 29728 29728 0 0.00 0 6019 25 0 0
sp_multiply_rows_for_qdistr 21914 21914 0 0.00 0 50118 159 0 0
sp_pay_from_customer 7587 7587 0 0.00 4 10097 102 0 0
sp_pay_to_supplier 6213 6213 0 0.00 4 15252 126 0 0
sp_payment_common 13800 13800 0 0.00 1 12284 24 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 14226 320 0 0
sp_qd_handle_on_invoice_upd_sts 11079 11079 0 0.00 0 20777 321 0 0
sp_qd_handle_on_reserve_upd_sts 10536 10536 0 0.00 0 27810 64 0 0
sp_reserve_write_off 10536 10536 0 0.00 6 27827 180 0 0
sp_supplier_invoice 5975 5975 0 0.00 5 96975 3356 0 0
sp_supplier_order 5942 5942 0 0.00 4 28964 1214 0 0
srv_aggregate_perf_data 117 117 0 0.00 2374 116137 15486 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1192 134717 43412 0 0
srv_find_qd_qs_mism 1080094 1080094 0 0.00 0 11468 6 0 0
srv_make_invnt_saldo 615 615 0 0.00 38 92969 1086 0 0
srv_make_money_saldo 816 816 0 0.00 0 2696 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 182500 331372 271180 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5410 5410 0 0.00 0 10021 148 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5194 104 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 1627 13 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 611 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 763 763 0 0.00 0 1154 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 517 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1370 20 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 27376 1663 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 3698 12 0 0
t$perf-norm:sp_client_order 21914 21914 0 0.00 0 8812 16 0 0
t$perf-norm:sp_customer_reserve 1117 1117 0 0.00 0 3590 12 0 0
t$perf-norm:sp_pay_from_customer 7587 7587 0 0.00 0 2840 10 0 0
t$perf-norm:sp_pay_to_supplier 6213 6213 0 0.00 0 6879 12 0 0
t$perf-norm:sp_reserve_write_off 10536 10536 0 0.00 0 10743 11 0 0
t$perf-norm:sp_supplier_invoice 5975 5975 0 0.00 0 7287 18 0 0
t$perf-norm:sp_supplier_order 5942 5942 0 0.00 0 6208 14 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 638 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 262 7 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 1007 22 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 3760 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 11042 10784 258 2.33 0 8418 20 0 258
v_all_customers 39932 39932 0 0.00 0 157 1 0 0
v_all_suppliers 32514 32514 0 0.00 0 23 1 0 0
v_all_wares 43828 43828 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 5739 23 0 0
v_cancel_client_order 3702 3702 0 0.00 0 5086 9 0 0
v_cancel_customer_prepayment 1526 1526 0 0.00 0 1612 12 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 1233 5 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2487 10 0 0
v_cancel_supplier_invoice 896 864 32 3.57 0 3629 20 0 32
v_cancel_supplier_order 874 874 0 0.00 0 557 4 0 0
v_cancel_write_off 2080 2080 0 0.00 0 2477 13 0 0
v_max_id_clo_ord 5942 5942 0 0.00 0 764 1 0 0
v_max_id_clo_res 1117 1117 0 0.00 0 2251 20 0 0
v_max_id_ord_sup 5966 5966 0 0.00 0 8340 6 0 0
v_max_non_paid_invoice 1864 1864 0 0.00 0 442 3 0 0
v_max_non_paid_realizn 4042 4042 0 0.00 0 2150 5 0 0
v_min_id_clo_ord 5942 5942 0 0.00 0 2489 5 0 0
v_min_id_clo_res 1117 1117 0 0.00 0 7259 16 0 0
v_min_id_ord_sup 5966 5966 0 0.00 0 6309 12 0 0
v_min_non_paid_invoice 6213 6213 0 0.00 0 12280 17 0 0
v_min_non_paid_realizn 7587 7587 0 0.00 0 10076 15 0 0
v_our_firm 10986 10986 0 0.00 0 115 1 0 0
v_reserve_write_off 21072 21072 0 0.00 0 8466 9 0 0
x_lock_depdocs_on_canc_invoice 11625 11625 0 0.00 0 21676 117 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 14971 349 0 0
x_make_qstorno_1000_1200 5942 5942 0 0.00 1 28936 1145 0 0
x_make_qstorno_1200_2000 5975 5975 0 0.00 2 96939 3191 0 0
x_make_qstorno_2100_3300 486786 486786 0 0.00 0 84788 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2022 22:39:38 to 06-12-2022 22:39:42.

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
20:08:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 21335 8192000000 9892372720 10195296256 974081184 10.627 100 91 182 0 1004227376 5910656 24235536
20:11:03 14981 8192000000 9928608656 10244071424 1009414592 10.970 100 86 172 0 1039310800 7615136 22281072
20:12:31 10498 8192000000 9964998016 10294812672 1054004688 11.399 100 81 162 0 1083368880 9316352 20047840
20:13:54 8200 8192000000 10023462224 10366693376 1117363264 12.002 100 80 160 0 1146305616 10340992 18601360
20:15:07 8193 8192000000 10068034496 10427809792 1163960688 12.440 100 69 138 0 1194908688 15343776 15604224
20:16:20 2273 8192000000 10126860768 10495807488 1219983488 12.962 100 82 160 1 1250312912 12325136 18004288
20:17:25 2211 8192000000 10161379232 10535079936 1251267568 13.250 100 82 164 0 1281220096 12317840 17634688
20:18:28 1966 8192000000 10183731120 10564730880 1272174560 13.442 100 80 161 1 1302834128 13486144 17173424
20:19:31 1437 8192000000 10207329904 10591383552 1293104016 13.632 100 80 161 0 1327341584 16766832 17470736
20:20:33 3386 8192000000 10201454592 10601025536 1304620448 13.737 100 43 86 0 1321574688 7403040 9551200
20:21:37 5374 8192000000 10193143104 10609397760 1312145296 13.806 100 34 68 0 1326448752 7196784 7106672
20:22:45 9121 8192000000 10200911088 10618732544 1318810128 13.866 100 22 44 0 1329534192 6193952 4530112
20:23:56 34862 8192000000 10203396848 10625363968 1319317520 13.871 100 28 55 0 1331831488 5188160 7325808
20:25:32 3808 8192000000 10230935664 10642378752 1327631568 13.946 100 39 79 0 1343915328 7356064 8927696
20:26:37 7441 8192000000 10234675152 10654265344 1333640656 14.000 100 15 30 0 1342692176 5849616 3201904
20:27:45 21388 8192000000 10228428880 10643050496 1293739312 13.638 97 24 49 1 1312832160 14174800 4918048
20:29:07 7231 8192000000 10198108224 10601521152 1255746928 13.291 95 56 113 1 1279608176 10676624 13184624
20:30:19 8405 8192000000 10147880112 10554609664 1241733200 13.162 97 34 68 0 1256076496 6387536 7955760
20:31:29 8038 8192000000 10134546784 10533347328 1224569904 13.004 98 17 35 0 1234076400 5361344 4145152
20:32:41 46421 8192000000 10163915248 10560892928 1239740416 13.144 98 35 68 0 1252762080 5105680 7915984
20:34:46 8529 8192000000 10168180400 10579697664 1279194896 13.506 100 25 51 1 1294735024 10365136 5174992
20:35:58 7043 8192000000 10199223056 10607947776 1301445616 13.708 100 35 71 0 1315351840 4876112 9030112
20:37:05 11924 8192000000 10204073824 10616442880 1310465760 13.790 100 19 39 0 1321054672 5385264 5203648
20:38:20 84803 8192000000 10247515808 10656903168 1336882752 14.029 100 54 106 3 1360915296 11887808 12144736
20:40:48 13066 8192000000 10262615504 10676953088 1354254816 14.186 100 59 116 0 1378976128 11428464 13292848
20:42:06 5606 8192000000 10292218384 10703986688 1371109040 14.337 100 63 123 0 1400622496 15412464 14100992
20:43:21 6908 8192000000 10292588480 10713460736 1377029968 14.390 100 51 101 1 1408087664 18831952 12225744
20:44:32 10603 8192000000 10300995968 10721464320 1383207984 14.445 100 49 98 0 1415715088 20241328 12265776
20:45:48 11439 8192000000 10310447584 10737565696 1392607136 14.529 100 38 76 0 1428300576 25948000 9745440
20:47:04 7181 8192000000 10327157856 10751029248 1401227408 14.606 100 47 90 1 1437960288 25389152 11343728
20:48:17 3134 8192000000 10348003152 10765639680 1410596544 14.689 100 75 147 1 1452768352 25393792 16778016
20:49:23 1192 8192000000 10360510480 10775334912 1415987536 14.737 100 86 171 1 1467224912 31181248 20056128
20:50:27 7590 8192000000 10348681904 10767695872 1408077440 14.667 99 59 117 1 1453695632 30159152 15459040
20:51:38 3882 8192000000 10360040752 10780790784 1420249936 14.775 100 61 122 0 1470238512 34726224 15262352
20:52:50 2405 8192000000 10370242848 10781429760 1415316032 14.731 99 83 164 1 1469532448 34357984 19858432
20:53:56 2985 8192000000 10335192816 10741100544 1370362432 14.330 98 81 159 1 1430152752 39656368 20133952
20:55:08 5944 8192000000 10357275824 10762657792 1392276672 14.526 100 77 154 0 1455039648 43729840 19033136
20:56:21 1928 8192000000 10358616848 10759913472 1388141616 14.489 98 83 164 1 1458887184 49321632 21423936
20:57:29 8087 8192000000 10333510656 10746966016 1370414624 14.331 99 51 100 0 1428067856 43906704 13746528
20:58:40 8415 8192000000 10339827824 10767568896 1391309664 14.518 100 45 88 1 1448862816 45644384 11908768
20:59:52 18904 8192000000 10349500032 10786439168 1395540752 14.555 100 44 88 0 1462507776 54640528 12326496
21:01:14 39434 8192000000 10315503296 10753048576 1357574048 14.216 98 24 48 0 1403166080 38582112 7009920
21:02:57 17298 8192000000 10299936208 10745221120 1363396848 14.268 100 31 61 2 1412669040 40719840 8552352
21:04:21 15312 8192000000 10305037840 10749628416 1369521168 14.323 100 19 39 0 1407718256 33309120 4887968
21:05:38 38040 8192000000 10271698304 10723196928 1350129440 14.149 99 8 16 0 1368918976 17092240 1697296
21:07:30 82228 8192000000 10278113728 10711867392 1343695392 14.091 98 14 28 1 1360289424 13709664 2884368
21:09:59 59968 8192000000 10300612880 10739314688 1362161040 14.257 99 23 44 1 1383066960 16322576 4583344
21:12:02 63496 8192000000 10272574640 10710798336 1319182832 13.869 96 42 82 1 1334347392 6316784 8847776
21:14:14 75039 8192000000 10283850512 10724012032 1365060960 14.283 99 28 56 0 1378158704 6068640 7029104
21:16:30 19509 8192000000 10313408608 10747187200 1389919936 14.505 100 51 102 0 1410700880 8820272 11960672
21:18:00 32403 8192000000 10300196624 10753503232 1390666096 14.512 100 26 51 0 1404519904 7818048 6035760
21:19:48 35818 8192000000 10308691648 10761715712 1399855488 14.594 100 18 36 0 1417210384 12781328 4573568
21:21:25 34663 8192000000 10294139232 10760044544 1381185920 14.427 99 12 24 0 1399289952 15006912 3097120
21:23:01 37881 8192000000 10270066800 10731401216 1366239696 14.293 99 15 30 0 1380019920 10093856 3686368
21:24:40 37055 8192000000 10296339904 10747256832 1386786448 14.477 100 32 64 2 1401737312 7495248 7455616
21:26:19 62253 8192000000 10282960544 10745065472 1375113632 14.373 99 8 16 0 1383518720 6921296 1483792
21:28:30 40612 8192000000 10277054624 10731335680 1328891616 13.957 96 16 32 1 1341058080 8539040 3627424
21:30:11 25974 8192000000 10293727552 10743103488 1366337248 14.294 98 26 52 1 1383345696 10918832 6089616
21:31:43 56590 8192000000 10278692416 10732158976 1360605520 14.243 99 18 35 0 1379354784 14314592 4434672
21:33:40 28788 8192000000 10292540320 10724945920 1340628960 14.063 97 38 74 1 1357703664 8802592 8272112
21:35:12 20053 8192000000 10286787312 10710642688 1335385104 14.016 98 52 104 0 1363537728 16568912 11583712
21:36:41 88363 8192000000 10245027760 10685583360 1282751888 13.538 95 14 28 0 1315255184 28946800 3556496
21:39:10 82014 8192000000 10278256096 10706022400 1340930672 14.066 98 52 102 2 1361776800 9995664 10850464
21:41:46 44908 8192000000 10279647952 10721697792 1357451520 14.214 99 27 54 0 1380195968 16519696 6224752
21:43:32 112120 8192000000 10289500768 10754088960 1355832528 14.200 98 23 46 0 1389175360 27435840 5906992
21:46:27 35578 8192000000 10268143392 10716778496 1357219600 14.212 98 17 34 1 1374857888 13706032 3932256
21:48:04 64559 8192000000 10248462288 10704912384 1283566160 13.546 94 15 30 1 1295330704 8152144 3612400
21:50:12 39726 8192000000 10274117776 10724589568 1344591776 14.099 98 17 34 0 1362452752 14052720 3808256
21:51:53 75134 8192000000 10271706144 10722873344 1301024736 13.705 95 25 51 1 1324202080 16869984 6307360
21:54:08 58014 8192000000 10270183664 10712145920 1288969664 13.595 94 19 37 0 1302439840 9450864 4019312
21:56:39 113592 8192000000 10306838000 10766688256 1382716032 14.441 100 27 54 0 1410757584 22277424 5764128
21:59:33 116445 8192000000 10276852624 10725847040 1343593696 14.090 97 20 40 0 1362835216 14448912 4792608
22:02:30 39398 8192000000 10294867728 10746695680 1380744992 14.423 100 29 58 0 1401977104 14706400 6525712
22:04:11 41269 8192000000 10282607728 10756624384 1388666400 14.494 100 7 14 0 1400975264 10384768 1924096
22:06:19 43956 8192000000 10285890400 10751033344 1381863408 14.433 99 20 40 0 1397252432 10368976 5020048
22:08:04 57797 8192000000 10283108480 10746695680 1368170512 14.311 99 15 30 0 1383663744 11936640 3556592
22:10:02 56086 8192000000 10282871824 10743037952 1374205472 14.365 99 14 26 1 1385611936 8474928 2931536
22:11:59 57275 8192000000 10284174672 10742706176 1339962464 14.057 96 12 24 0 1351004400 8341680 2700256
22:14:26 50480 8192000000 10269596000 10723307520 1325485744 13.926 96 10 20 0 1336127376 8481856 2159776
22:16:28 134717 8192000000 10284149152 10739056640 1295124944 13.651 94 12 24 0 1314810640 16986768 2698928
22:19:43 55582 8192000000 10292605520 10738225152 1346549040 14.116 98 25 49 0 1374567008 22838000 5179968
22:21:39 78248 8192000000 10265766688 10715250688 1309980656 13.786 96 18 34 2 1331444736 17508720 3955360
22:24:04 85429 8192000000 10243621184 10690035712 1303629440 13.728 96 26 52 0 1319331024 9756512 5945072
22:26:30 11048 8192000000 10240983520 10698645504 1339304864 14.051 99 11 22 0 1354031536 12346672 2380000
22:27:46 59707 8192000000 10259744688 10730532864 1344612352 14.099 99 8 16 1 1358093456 11756416 1724688
22:29:46 71560 8192000000 10275442704 10744754176 1378229360 14.401 100 14 28 0 1394806000 13184928 3391712
22:32:08 60134 8192000000 10281847488 10736373760 1353219968 14.176 98 23 43 0 1371756032 13420256 5115808
22:34:08 61756 8192000000 10252138544 10711580672 1304769328 13.739 95 16 32 0 1317672464 9128432 3774704
22:36:10 56201 8192000000 10273138576 10727256064 1339981312 14.057 98 29 58 0 1358855296 12034000 6839984
22:38:07 30968 8192000000 9221155264 9494319104 365415376 4.270 27 10 20 0 371407472 3536000 2456096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          250790
MON$OLDEST_ACTIVE               250791
MON$OLDEST_SNAPSHOT             250791
MON$NEXT_TRANSACTION            250791
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                       2024640
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Dec  6 22:38:49 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Dec  6 22:38:50 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

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