Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2021 19: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 16752688 kB
MemAvailable 31412780 kB
Buffers 0 kB
Cached 14886564 kB
SwapCached 0 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.9
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 2021-12-12 19:37:59
DTS_END                         2021-12-12 19:37:59.1780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47450
TRN_ID                          235713
ATT_ID                          671
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: 9450.88 22595 1134105
customer order: creation 169.10 327 20292
customer order: refuse 14.87 315 1784
order to supplier: creation 45.60 1302 5472
order to supplier: removal 3.46 97373 415
invoice (draft): creation 45.32 3193 5438
invoice (draft): removal 55.18 696 6621
invoice accept: apply 40.94 18205 4913
invoice accept: cancel 49.61 745 5953
customer reserve: creation 3830.02 179 459602
customer reserve: removal 4910.54 53 589265
realization accept: apply 81.48 168 9778
realization accept: cancel 77.27 111 9272
payment from customer: creation 58.05 103 6966
payment from customer: removal 5.81 54 697
payment to supplier: creation 48.33 134 5799
payment to supplier: removal 3.28 72 394
service: total inventory turnovers 4.38 1053 525
service: total monetary turnovers 6.71 32 805
service: aggregate perf. data 0.92 18129 110
service: refresh index statistics 0.03 309662 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-12-2021 19:38:31 to 12-12-2021 19:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1670
TEST_TIME 2 2855
TEST_TIME 3 3204
TEST_TIME 4 2849
TEST_TIME 5 4285
TEST_TIME 6 5008
TEST_TIME 7 5748
TEST_TIME 8 5935
TEST_TIME 9 6475
TEST_TIME 10 6530
TEST_TIME 11 6491
TEST_TIME 12 6428
TEST_TIME 13 6786
TEST_TIME 14 7225
TEST_TIME 15 7340
TEST_TIME 16 7326
TEST_TIME 17 7353
TEST_TIME 18 7527
TEST_TIME 19 7616
TEST_TIME 20 7879
TEST_TIME 21 8058
TEST_TIME 22 8087
TEST_TIME 23 8123
TEST_TIME 24 8047
TEST_TIME 25 8767
TEST_TIME 26 9054
TEST_TIME 27 9266
TEST_TIME 28 9554
TEST_TIME 29 9768
TEST_TIME 30 10359
TEST_TIME 31 10357
TEST_TIME 32 10107
TEST_TIME 33 9906
TEST_TIME 34 9764
TEST_TIME 35 10132
TEST_TIME 36 10040
TEST_TIME 37 10044
TEST_TIME 38 9886
TEST_TIME 39 9975
TEST_TIME 40 9847
TEST_TIME 41 9799
TEST_TIME 42 9679
TEST_TIME 43 9740
TEST_TIME 44 9664
TEST_TIME 45 9506
TEST_TIME 46 9457
TEST_TIME 47 9573
TEST_TIME 48 9472
TEST_TIME 49 9433
TEST_TIME 50 9474
TEST_TIME 51 9507
TEST_TIME 52 9494
TEST_TIME 53 9444
TEST_TIME 54 9300
TEST_TIME 55 9230
TEST_TIME 56 9198
TEST_TIME 57 9311
TEST_TIME 58 9292
TEST_TIME 59 9262
TEST_TIME 60 9267
TEST_TIME 61 9214
TEST_TIME 62 9271
TEST_TIME 63 9332
TEST_TIME 64 9413
TEST_TIME 65 9523
TEST_TIME 66 9586
TEST_TIME 67 9542
TEST_TIME 68 9521
TEST_TIME 69 9597
TEST_TIME 70 9591
TEST_TIME 71 9596
TEST_TIME 72 9711
TEST_TIME 73 9723
TEST_TIME 74 9604
TEST_TIME 75 9556
TEST_TIME 76 9480
TEST_TIME 77 9487
TEST_TIME 78 9419
TEST_TIME 79 9451
TEST_TIME 80 9407
TEST_TIME 81 9368
TEST_TIME 82 9288
TEST_TIME 83 9350
TEST_TIME 84 9385
TEST_TIME 85 9394
TEST_TIME 86 9401
TEST_TIME 87 9386
TEST_TIME 88 9394
TEST_TIME 89 9393
TEST_TIME 90 9392
TEST_TIME 91 9437
TEST_TIME 92 9464
TEST_TIME 93 9462
TEST_TIME 94 9523
TEST_TIME 95 9565
TEST_TIME 96 9660
TEST_TIME 97 9609
TEST_TIME 98 9522
TEST_TIME 99 9498
TEST_TIME 100 9467
TEST_TIME 101 9462
TEST_TIME 102 9417
TEST_TIME 103 9385
TEST_TIME 104 9352
TEST_TIME 105 9335
TEST_TIME 106 9334
TEST_TIME 107 9277
TEST_TIME 108 9297
TEST_TIME 109 9345
TEST_TIME 110 9332
TEST_TIME 111 9312
TEST_TIME 112 9366
TEST_TIME 113 9372
TEST_TIME 114 9378
TEST_TIME 115 9355
TEST_TIME 116 9380
TEST_TIME 117 9375
TEST_TIME 118 9374
TEST_TIME 119 9425
TEST_TIME 120 9454

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-12-2021 19:38:35 to 12-12-2021 19:38:38.

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 1559209 1559209 0 0.00 0 16602 6 0 0
doc_list_biud 1559209 1559209 0 0.00 0 34548 26 0 0
sp_add_invoice_to_stock 5078 4913 165 3.24 31 172345 18206 0 165
sp_cancel_adding_invoice 5953 5953 0 0.00 4 62499 746 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 12971 316 0 0
sp_cancel_customer_reserve 589265 589265 0 0.00 1 34551 54 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 3679 55 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 2786 72 0 0
sp_cancel_supplier_invoice 6639 6621 18 0.27 2 16408 697 0 18
sp_cancel_supplier_order 415 415 0 0.00 6 819520 97374 0 0
sp_cancel_write_off 9272 9272 0 0.00 1 21872 112 0 0
sp_client_order 20292 20292 0 0.00 3 17047 327 0 0
sp_customer_reserve 459602 459602 0 0.00 0 83330 179 0 0
sp_fill_shopping_cart 31185 31185 0 0.00 1 12514 35 0 0
sp_fill_shopping_cart_clo_res 459593 459593 0 0.00 0 13140 12 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 30898 269 0 0
sp_kill_cost_storno 16316 16316 0 0.00 0 14040 52 0 0
sp_kill_qstorno_ret_qs2qd 1194838 1194838 0 0.00 0 34546 29 0 0
sp_kill_qty_storno 627991 627991 0 0.00 0 34548 63 0 0
sp_lock_selected_doc 20421 20421 0 0.00 0 7159 5 0 0
sp_make_cost_storno 27446 27446 0 0.00 0 16553 51 0 0
sp_multiply_rows_for_pdistr 27446 27446 0 0.00 0 15457 30 0 0
sp_multiply_rows_for_qdistr 20292 20292 0 0.00 0 13325 152 0 0
sp_pay_from_customer 6966 6966 0 0.00 4 15518 103 0 0
sp_pay_to_supplier 5799 5799 0 0.00 4 15511 135 0 0
sp_payment_common 12765 12765 0 0.00 2 15453 34 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 12954 286 0 0
sp_qd_handle_on_invoice_upd_sts 10856 10856 0 0.00 0 21477 321 0 0
sp_qd_handle_on_reserve_upd_sts 9778 9778 0 0.00 0 14000 53 0 0
sp_reserve_write_off 9778 9778 0 0.00 6 15447 169 0 0
sp_supplier_invoice 5438 5438 0 0.00 6 84625 3194 0 0
sp_supplier_order 5472 5472 0 0.00 5 43418 1303 0 0
srv_aggregate_perf_data 111 110 1 0.90 2513 146053 18130 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1221 156228 48342 0 0
srv_find_qd_qs_mism 1086682 1086682 0 0.00 0 16043 6 0 0
srv_make_invnt_saldo 525 525 0 0.00 28 28339 1053 0 0
srv_make_money_saldo 805 805 0 0.00 1 4193 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 195865 510665 309662 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 12903 184 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 3319 84 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 4784 17 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 506 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2774 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 2687 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1402 14 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 28102 1673 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 2778 6 0 0
t$perf-norm:sp_client_order 20292 20292 0 0.00 0 9694 19 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 803 4 0 0
t$perf-norm:sp_pay_from_customer 6966 6966 0 0.00 0 3128 7 0 0
t$perf-norm:sp_pay_to_supplier 5799 5799 0 0.00 0 9132 11 0 0
t$perf-norm:sp_reserve_write_off 9778 9778 0 0.00 0 8168 13 0 0
t$perf-norm:sp_supplier_invoice 5438 5438 0 0.00 0 7534 21 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 8427 20 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 909 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1514 43 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 7174 35 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 3048 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10134 9804 330 3.25 0 3373 17 0 330
v_all_customers 37352 37352 0 0.00 0 39 1 0 0
v_all_suppliers 30380 30380 0 0.00 0 24 1 0 0
v_all_wares 40581 40581 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 2889 10 0 0
v_cancel_client_order 3568 3568 0 0.00 0 4113 7 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 522 4 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2943 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2775 6 0 0
v_cancel_supplier_invoice 886 850 36 4.06 0 681 14 0 36
v_cancel_supplier_order 830 830 0 0.00 0 560 4 0 0
v_cancel_write_off 1914 1914 0 0.00 0 3895 18 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 285 1 0 0
v_max_id_clo_res 940 940 0 0.00 0 2857 24 0 0
v_max_id_ord_sup 5423 5423 0 0.00 0 2531 3 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 15103 14 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 4651 6 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 8413 13 0 0
v_min_id_clo_res 940 940 0 0.00 0 3414 12 0 0
v_min_id_ord_sup 5423 5423 0 0.00 0 4839 7 0 0
v_min_non_paid_invoice 5799 5799 0 0.00 0 15125 23 0 0
v_min_non_paid_realizn 6966 6966 0 0.00 0 13893 24 0 0
v_our_firm 9874 9874 0 0.00 0 21 1 0 0
v_reserve_write_off 19556 19556 0 0.00 0 3301 5 0 0
x_lock_depdocs_on_canc_invoice 12149 12149 0 0.00 0 18982 115 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 11812 362 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 1 43412 1224 0 0
x_make_qstorno_1200_2000 5438 5438 0 0.00 2 84219 3075 0 0
x_make_qstorno_2100_3300 459602 459602 0 0.00 0 83328 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2021 19:38:39 to 12-12-2021 19:38:43.

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:02 4 8192000000 8799413408 8810889216 5805408 0.070 2 1 2 0 5905040 56848 42784
17:09:04 19343 8192000000 9889498336 10198679552 974178208 10.627 100 85 170 0 1002702016 5671056 22852752
17:10:29 16048 8192000000 9944634704 10267754496 1029548896 11.164 100 89 178 0 1060013712 7400016 23064800
17:11:49 23561 8192000000 9990556640 10322489344 1071263488 11.564 100 85 170 3 1101168976 9000192 20905296
17:13:24 9633 8192000000 10045106000 10393931776 1130332496 12.124 100 86 171 0 1160826992 10779952 19714544
17:14:39 7651 8192000000 10085371872 10446934016 1171511280 12.511 100 83 162 2 1203716864 12992736 19212848
17:15:49 6526 8192000000 10137511856 10507276288 1221380800 12.974 100 81 162 0 1252653232 13067312 18205120
17:16:58 3503 8192000000 10163436320 10536144896 1250503536 13.243 100 80 159 2 1279746400 12079504 17163360
17:18:04 3259 8192000000 10193530208 10572750848 1272931776 13.448 100 85 170 3 1304942240 12488352 19522112
17:19:09 1649 8192000000 10206347632 10591440896 1287288592 13.580 100 93 186 4 1320718448 12838800 20591056
17:20:14 3121 8192000000 10200994208 10596159488 1296747680 13.666 100 65 131 0 1323134064 12024608 14361776
17:21:18 52977 8192000000 10204683456 10617823232 1306442432 13.754 100 28 56 0 1318787568 5200768 7144368
17:23:15 4862 8192000000 10217358624 10633428992 1324028720 13.913 100 37 74 1 1339425856 7026096 8371040
17:24:23 6185 8192000000 10222099488 10640855040 1333379152 13.998 100 23 47 1 1344465600 5716304 5370144
17:25:32 59614 8192000000 10244653856 10655264768 1339763712 14.055 100 50 102 5 1357480272 5607184 12109376
17:27:33 5494 8192000000 10204112112 10618191872 1308136304 13.769 99 37 74 4 1324689264 7965776 8587184
17:28:42 12686 8192000000 10192727904 10611605504 1268276336 13.406 96 18 36 1 1278417840 6038272 4103232
17:30:00 11293 8192000000 10177762016 10584023040 1260564224 13.335 97 21 41 0 1270990336 5708256 4717856
17:31:16 10004 8192000000 10155940576 10565410816 1233558112 13.087 96 23 46 1 1244975776 5421136 5996528
17:32:28 10428 8192000000 10153263472 10558017536 1261767760 13.346 100 22 44 2 1272132832 5300160 5064912
17:33:47 9170 8192000000 10157714464 10558124032 1250790832 13.245 99 36 72 1 1265850704 6413024 8646848
17:34:59 24877 8192000000 10168075424 10583080960 1280667056 13.519 100 25 50 0 1294096208 7473152 5956000
17:36:52 7507 8192000000 10192048208 10598821888 1289294400 13.598 100 34 68 0 1303525504 6784416 7446688
17:37:59 28262 8192000000 10220031760 10619203584 1303867776 13.730 100 40 81 2 1319866128 6073648 9924704
17:39:34 10574 8192000000 10242111216 10651029504 1334799952 14.011 100 44 87 0 1353828992 9524928 9504112
17:40:48 78205 8192000000 10258980144 10682052608 1351674960 14.163 100 31 62 0 1371697456 12738352 7284144
17:43:18 14924 8192000000 10280462528 10699452416 1367298272 14.303 100 46 90 1 1388940688 11636400 10006016
17:44:40 3267 8192000000 10298415440 10713640960 1375690704 14.378 100 68 133 2 1407512848 15868672 15953472
17:45:45 4635 8192000000 10298553232 10719346688 1372093408 14.346 99 59 119 0 1401960608 15393024 14474176
17:46:54 8237 8192000000 10326124304 10738528256 1396313216 14.562 100 68 136 2 1431884752 20279296 15292240
17:48:06 3220 8192000000 10316910784 10736066560 1397678160 14.574 100 58 116 0 1425379440 14320448 13380832
17:49:16 10790 8192000000 10309303280 10733359104 1383034336 14.444 99 56 111 1 1411335104 14475568 13825200
17:50:45 4594 8192000000 10347236352 10765914112 1413868208 14.718 100 70 138 0 1455849792 24671008 17310576
17:51:59 4774 8192000000 10344346016 10764812288 1417130384 14.747 100 64 126 1 1454474784 22028976 15315424
17:53:08 8244 8192000000 10346208096 10761486336 1407075248 14.658 99 65 130 2 1447846592 24351232 16420112
17:54:20 4736 8192000000 10342842144 10766082048 1408539312 14.671 100 57 112 0 1453965936 31013632 14412992
17:55:32 2144 8192000000 10370843008 10781069312 1420187392 14.774 100 82 163 1 1476161040 35198432 20775216
17:56:39 1219 8192000000 10385701888 10796101632 1427512368 14.839 100 86 172 2 1488769520 38777072 22480080
17:57:46 5641 8192000000 10338980320 10760089600 1380977856 14.425 99 57 113 2 1437332224 40834928 15519440
17:58:55 11429 8192000000 10336384048 10761474048 1388523568 14.493 99 35 70 2 1435066960 37362880 9180512
18:00:26 9725 8192000000 10326899312 10755919872 1391911424 14.523 100 33 64 2 1436895296 36351920 8631952
18:01:41 10808 8192000000 10329284320 10747715584 1361987872 14.255 99 50 102 2 1423930560 48139136 13803552
18:03:06 20668 8192000000 10314494512 10753331200 1360491184 14.242 99 28 56 1 1410045824 41777712 7776928
18:04:34 29908 8192000000 10292800368 10729500672 1344744560 14.100 98 14 29 3 1384469056 35752528 3971968
18:06:05 40099 8192000000 10298456896 10743627776 1356319968 14.204 100 16 32 0 1403420784 42952848 4147968
18:08:06 20700 8192000000 10276565744 10700500992 1344452944 14.098 99 55 111 3 1369797536 11139664 14204928
18:09:36 112905 8192000000 10285999824 10724663296 1343987056 14.093 98 35 69 0 1371895776 19232176 8676544
18:12:30 91884 8192000000 10266554064 10720329728 1316655872 13.846 96 10 20 0 1338443488 19481360 2306256
18:15:03 52227 8192000000 10278930752 10711326720 1318905568 13.867 96 27 55 1 1334889632 9672432 6311632
18:16:59 49226 8192000000 10276168656 10727448576 1363510560 14.269 99 15 30 1 1382539376 15690896 3337920
18:18:48 118395 8192000000 10278819360 10737754112 1355028720 14.193 98 14 28 0 1368737680 10404848 3304112
18:22:01 63180 8192000000 10284720000 10737000448 1387019760 14.479 100 23 45 1 1402649408 9848240 5781408
18:24:15 71790 8192000000 10295147472 10763059200 1373309952 14.357 98 11 22 0 1388236480 12284944 2641584
18:26:31 40474 8192000000 10318361968 10769190912 1405172384 14.641 100 29 58 1 1421504016 9949712 6381920
18:28:15 67427 8192000000 10300631408 10769027072 1404113088 14.632 100 15 30 0 1422541424 14545840 3882496
18:30:40 66997 8192000000 10311139264 10767749120 1409497920 14.679 100 30 60 1 1423852848 6525280 7829648
18:32:53 62840 8192000000 10325937680 10798096384 1423819088 14.807 100 16 32 0 1447026016 19567568 3639360
18:35:00 42654 8192000000 10296273456 10763403264 1388395536 14.492 98 4 8 1 1408089408 18843552 850320
18:36:44 26598 8192000000 10313289616 10769608704 1408739984 14.673 100 20 40 1 1420657040 7258720 4658336
18:38:11 36816 8192000000 10331507824 10778193920 1404571808 14.636 99 27 54 1 1418645136 8202752 5870576
18:39:48 69962 8192000000 10290621248 10747551744 1355995104 14.201 96 12 24 3 1372781408 13973200 2813104
18:41:58 51279 8192000000 10312816592 10779189248 1360680112 14.243 97 11 22 1 1388916416 25529024 2707280
18:43:50 68473 8192000000 10290984112 10739077120 1350592624 14.153 97 22 44 0 1374751456 19421936 4736896
18:45:59 69877 8192000000 10297142160 10735095808 1357704064 14.217 98 21 42 1 1377403216 15032176 4666976
18:48:12 55863 8192000000 10294418496 10734669824 1321564000 13.891 96 33 66 1 1343712672 14906688 7241984
18:50:08 134450 8192000000 10284564080 10728333312 1365351472 14.285 99 20 40 3 1381077840 11479872 4246496
18:53:29 34708 8192000000 10268668512 10707181568 1331521536 13.981 98 23 46 0 1346116752 9497648 5097568
18:55:15 79396 8192000000 10242542368 10697138176 1294128496 13.642 95 7 14 1 1308641280 12781232 1731552
18:57:42 66350 8192000000 10240258976 10686574592 1286782640 13.575 95 24 49 1 1304696208 12126160 5787408
18:59:49 74745 8192000000 10246511552 10705027072 1309430512 13.781 97 11 22 1 1329276064 17370880 2474672
19:02:12 134912 8192000000 10248393008 10696646656 1298489296 13.682 96 14 28 1 1311690928 9974080 3227552
19:05:28 55066 8192000000 10255903056 10701750272 1335315600 14.015 99 22 44 0 1349702080 9446272 4940208
19:07:24 36812 8192000000 10269915536 10715328512 1362047328 14.256 100 28 56 0 1379315616 11324080 5944208
19:09:02 156228 8192000000 10294518240 10756722688 1345446672 14.106 97 8 16 1 1365886656 18714480 1725504
19:12:38 118455 8192000000 10291984048 10752954368 1346221456 14.113 97 14 28 1 1376045280 26821888 3001936
19:16:23 61480 8192000000 10280843488 10741043200 1371930736 14.344 99 12 24 0 1386213200 11672672 2609792
19:19:06 55933 8192000000 10278499056 10738864128 1383976512 14.452 100 13 25 0 1394528544 7745968 2806064
19:21:05 71143 8192000000 10301650864 10762104832 1387102256 14.480 99 24 49 2 1408536016 16117136 5316624
19:23:32 66029 8192000000 10304909488 10755706880 1395701312 14.557 100 28 55 2 1414463696 12566592 6195792
19:26:54 85995 8192000000 10298659680 10759045120 1379251360 14.410 99 9 18 0 1392148208 10955056 1941792
19:29:21 68340 8192000000 10283037008 10733477888 1342611936 14.081 96 25 48 1 1358076896 9770016 5694944
19:31:38 44589 8192000000 10303867728 10748555264 1378169696 14.400 99 31 62 1 1394862144 10013856 6678592
19:33:22 39228 8192000000 10300185536 10743746560 1364549920 14.278 98 23 46 1 1380717072 11532608 4634544
19:35:03 12453 8192000000 10300231936 10733113344 1377396272 14.393 100 47 94 0 1398966512 11148720 10421520
19:36:17 45132 8192000000 10288261728 10742628352 1363996640 14.273 99 16 32 0 1386959120 19202512 3759968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2021 19:38:45 to 12-12-2021 19:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 165 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-12-2021 19:39:01 to 12-12-2021 19:39: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          235552
MON$OLDEST_ACTIVE               235836
MON$OLDEST_SNAPSHOT             235836
MON$NEXT_TRANSACTION            235836
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                       2015616
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sun Dec 12 19:38:03 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
--- end of diff output ---

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

12.12.21 19:39: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
2021-12-12 19:39:06 No severe PSQL-related problems occured
12.12.21 19:39:08. End of report.