Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 22: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 16383592 kB
MemAvailable 31348316 kB
Buffers 0 kB
Cached 15256228 kB
SwapCached 440 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-25 22:38:05
DTS_END                         2021-12-25 22:38:05.6860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(645:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55092
TRN_ID                          240256
ATT_ID                          718
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: 9485.09 21924 1138211
customer order: creation 176.17 320 21140
customer order: refuse 15.16 360 1819
order to supplier: creation 46.03 1283 5524
order to supplier: removal 3.54 99598 425
invoice (draft): creation 47.36 3271 5683
invoice (draft): removal 55.88 756 6706
invoice accept: apply 42.08 15273 5049
invoice accept: cancel 50.60 723 6072
customer reserve: creation 3833.53 152 460023
customer reserve: removal 4910.38 56 589245
realization accept: apply 86.07 187 10328
realization accept: cancel 84.52 111 10142
payment from customer: creation 61.41 120 7369
payment from customer: removal 5.93 77 711
payment to supplier: creation 50.37 160 6044
payment to supplier: removal 3.84 77 461
service: total inventory turnovers 4.51 1202 541
service: total monetary turnovers 6.87 40 824
service: aggregate perf. data 0.84 22011 101
service: refresh index statistics 0.03 292711 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2021 22:39:08 to 25-12-2021 22:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3809
TEST_TIME 2 4003
TEST_TIME 3 4584
TEST_TIME 4 4990
TEST_TIME 5 4673
TEST_TIME 6 4574
TEST_TIME 7 4712
TEST_TIME 8 5186
TEST_TIME 9 5213
TEST_TIME 10 5795
TEST_TIME 11 5774
TEST_TIME 12 6448
TEST_TIME 13 6916
TEST_TIME 14 7296
TEST_TIME 15 7373
TEST_TIME 16 7504
TEST_TIME 17 7886
TEST_TIME 18 8246
TEST_TIME 19 8138
TEST_TIME 20 8553
TEST_TIME 21 8959
TEST_TIME 22 9286
TEST_TIME 23 9488
TEST_TIME 24 9344
TEST_TIME 25 9932
TEST_TIME 26 10146
TEST_TIME 27 10403
TEST_TIME 28 10466
TEST_TIME 29 11099
TEST_TIME 30 10996
TEST_TIME 31 10785
TEST_TIME 32 10654
TEST_TIME 33 10451
TEST_TIME 34 10656
TEST_TIME 35 10533
TEST_TIME 36 10501
TEST_TIME 37 10475
TEST_TIME 38 10297
TEST_TIME 39 10416
TEST_TIME 40 10415
TEST_TIME 41 10384
TEST_TIME 42 10375
TEST_TIME 43 10356
TEST_TIME 44 10294
TEST_TIME 45 10318
TEST_TIME 46 10134
TEST_TIME 47 10028
TEST_TIME 48 10162
TEST_TIME 49 10032
TEST_TIME 50 10041
TEST_TIME 51 10003
TEST_TIME 52 9938
TEST_TIME 53 9892
TEST_TIME 54 10066
TEST_TIME 55 9961
TEST_TIME 56 9915
TEST_TIME 57 9959
TEST_TIME 58 9866
TEST_TIME 59 9842
TEST_TIME 60 9748
TEST_TIME 61 9829
TEST_TIME 62 9750
TEST_TIME 63 9678
TEST_TIME 64 9652
TEST_TIME 65 9669
TEST_TIME 66 9695
TEST_TIME 67 9744
TEST_TIME 68 9815
TEST_TIME 69 9786
TEST_TIME 70 9904
TEST_TIME 71 9832
TEST_TIME 72 9891
TEST_TIME 73 9878
TEST_TIME 74 10011
TEST_TIME 75 10000
TEST_TIME 76 10014
TEST_TIME 77 9967
TEST_TIME 78 9885
TEST_TIME 79 9854
TEST_TIME 80 9829
TEST_TIME 81 9828
TEST_TIME 82 9770
TEST_TIME 83 9734
TEST_TIME 84 9676
TEST_TIME 85 9636
TEST_TIME 86 9566
TEST_TIME 87 9548
TEST_TIME 88 9635
TEST_TIME 89 9598
TEST_TIME 90 9597
TEST_TIME 91 9554
TEST_TIME 92 9567
TEST_TIME 93 9680
TEST_TIME 94 9634
TEST_TIME 95 9671
TEST_TIME 96 9744
TEST_TIME 97 9765
TEST_TIME 98 9790
TEST_TIME 99 9783
TEST_TIME 100 9753
TEST_TIME 101 9825
TEST_TIME 102 9830
TEST_TIME 103 9797
TEST_TIME 104 9751
TEST_TIME 105 9686
TEST_TIME 106 9691
TEST_TIME 107 9647
TEST_TIME 108 9601
TEST_TIME 109 9579
TEST_TIME 110 9565
TEST_TIME 111 9562
TEST_TIME 112 9507
TEST_TIME 113 9475
TEST_TIME 114 9469
TEST_TIME 115 9500
TEST_TIME 116 9499
TEST_TIME 117 9558
TEST_TIME 118 9567
TEST_TIME 119 9564
TEST_TIME 120 9490

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 22:39:12 to 25-12-2021 22:39:15.

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 1564162 1564162 0 0.00 0 39616 6 0 0
doc_list_biud 1564162 1564162 0 0.00 0 96262 27 0 0
sp_add_invoice_to_stock 5185 5049 136 2.62 34 150416 15274 0 136
sp_cancel_adding_invoice 6072 6072 0 0.00 5 81798 724 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 9847 361 0 0
sp_cancel_customer_reserve 589245 589245 0 0.00 1 25404 56 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 5384 78 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 2 4711 78 0 0
sp_cancel_supplier_invoice 6719 6706 13 0.19 1 96270 757 0 13
sp_cancel_supplier_order 425 425 0 0.00 5 969614 99599 0 0
sp_cancel_write_off 10142 10142 0 0.00 1 8141 112 0 0
sp_client_order 21140 21140 0 0.00 3 15693 320 0 0
sp_customer_reserve 460023 460023 0 0.00 0 32387 152 0 0
sp_fill_shopping_cart 32336 32336 0 0.00 1 27610 41 0 0
sp_fill_shopping_cart_clo_res 460014 460014 0 0.00 0 12908 12 0 0
sp_get_clo_for_invoice 5040 5040 0 0.00 0 18067 265 0 0
sp_kill_cost_storno 17386 17386 0 0.00 0 8936 58 0 0
sp_kill_qstorno_ret_qs2qd 1195763 1195763 0 0.00 0 96259 30 0 0
sp_kill_qty_storno 629777 629777 0 0.00 0 96261 66 0 0
sp_lock_selected_doc 21267 21267 0 0.00 0 7589 3 0 0
sp_make_cost_storno 28781 28781 0 0.00 0 39591 57 0 0
sp_multiply_rows_for_pdistr 28781 28781 0 0.00 0 15723 39 0 0
sp_multiply_rows_for_qdistr 21140 21140 0 0.00 0 15671 152 0 0
sp_pay_from_customer 7369 7369 0 0.00 4 18886 121 0 0
sp_pay_to_supplier 6044 6044 0 0.00 5 43969 160 0 0
sp_payment_common 13413 13413 0 0.00 1 18880 31 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 9840 340 0 0
sp_qd_handle_on_invoice_upd_sts 11112 11112 0 0.00 0 20116 322 0 0
sp_qd_handle_on_reserve_upd_sts 10328 10328 0 0.00 0 17232 65 0 0
sp_reserve_write_off 10328 10328 0 0.00 6 17248 188 0 0
sp_supplier_invoice 5683 5683 0 0.00 6 74522 3272 0 0
sp_supplier_order 5524 5524 0 0.00 5 69785 1283 0 0
srv_aggregate_perf_data 101 101 0 0.00 2532 432315 22011 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2120 146401 52585 0 0
srv_find_qd_qs_mism 1089684 1089684 0 0.00 0 9131 4 0 0
srv_make_invnt_saldo 541 541 0 0.00 44 84764 1202 0 0
srv_make_money_saldo 824 824 0 0.00 1 7243 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 212420 426459 292711 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5049 5049 0 0.00 0 17690 176 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 3021 97 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 1338 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 5481 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 3799 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 298 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 646 14 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 21234 1565 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 4951 16 0 0
t$perf-norm:sp_client_order 21140 21140 0 0.00 0 12378 20 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 5579 13 0 0
t$perf-norm:sp_pay_from_customer 7369 7369 0 0.00 0 15528 14 0 0
t$perf-norm:sp_pay_to_supplier 6044 6044 0 0.00 0 7290 10 0 0
t$perf-norm:sp_reserve_write_off 10328 10328 0 0.00 0 15979 17 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 16234 30 0 0
t$perf-norm:sp_supplier_order 5524 5524 0 0.00 0 7162 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 736 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 11257 197 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 2600 31 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 5842 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10352 10080 272 2.62 0 3756 16 0 272
v_all_customers 38632 38632 0 0.00 0 346 1 0 0
v_all_suppliers 31040 31040 0 0.00 0 19 1 0 0
v_all_wares 42280 42280 0 0.00 0 352 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1142 8 0 0
v_cancel_client_order 3638 3638 0 0.00 0 3504 4 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1047 5 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 3362 6 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 1479 5 0 0
v_cancel_supplier_invoice 814 788 26 3.19 0 4851 22 0 26
v_cancel_supplier_order 850 850 0 0.00 0 1345 8 0 0
v_cancel_write_off 2008 2008 0 0.00 0 3621 12 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 1502 2 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 1713 16 0 0
v_max_id_ord_sup 5674 5674 0 0.00 0 5745 6 0 0
v_max_non_paid_invoice 1720 1720 0 0.00 0 4180 10 0 0
v_max_non_paid_realizn 3853 3853 0 0.00 0 7699 7 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 6032 9 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 6459 13 0 0
v_min_id_ord_sup 5674 5674 0 0.00 0 12634 22 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 6515 19 0 0
v_min_non_paid_realizn 7369 7369 0 0.00 0 18877 24 0 0
v_our_firm 10680 10680 0 0.00 0 13 1 0 0
v_reserve_write_off 20654 20654 0 0.00 0 3685 5 0 0
x_lock_depdocs_on_canc_invoice 12384 12384 0 0.00 0 18131 116 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 31193 419 0 0
x_make_qstorno_1000_1200 5524 5524 0 0.00 1 69759 1200 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 3 74466 3119 0 0
x_make_qstorno_2100_3300 460023 460023 0 0.00 0 32385 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 22:39:16 to 25-12-2021 22:39:20.

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:09 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:10 25536 8192000000 9904200784 10214694912 981822928 10.702 100 83 166 0 1008756384 5823392 21110064
20:10:40 24014 8192000000 9948832960 10267635712 1020385728 11.076 100 87 174 0 1050721968 7532352 22803888
20:12:06 21126 8192000000 9980743520 10311983104 1060563536 11.462 100 88 176 0 1092044000 9220736 22259728
20:13:32 14109 8192000000 10040202544 10387804160 1120720944 12.034 100 74 148 1 1149036608 10282272 18033392
20:14:52 11086 8192000000 10079111584 10441076736 1173183232 12.527 100 72 144 1 1201321664 11985760 16152672
20:16:06 5281 8192000000 10132043328 10500833280 1217222736 12.936 100 83 165 3 1248939744 13285088 18431920
20:17:17 10052 8192000000 10155009936 10536333312 1247740304 13.217 100 67 134 0 1275121872 12704032 14677536
20:18:29 3058 8192000000 10194591952 10572664832 1279500336 13.508 100 89 178 1 1315053744 16044912 19508496
20:19:35 2859 8192000000 10216841696 10603978752 1304170544 13.733 100 90 181 3 1336091952 12007408 19914000
20:20:40 2672 8192000000 10201058016 10608504832 1309186096 13.779 100 48 97 0 1329836736 9864960 10785680
20:21:45 10832 8192000000 10200839488 10612965376 1317047552 13.850 100 34 68 2 1331738848 5586800 9104496
20:22:57 28123 8192000000 10219792224 10627264512 1318250064 13.861 100 44 88 2 1335603200 6396928 10956208
20:24:26 7497 8192000000 10215127776 10636627968 1326502048 13.936 100 32 61 1 1343991248 10172832 7316368
20:25:34 11662 8192000000 10230994992 10649239552 1333846688 14.002 100 17 34 0 1343057136 5317568 3892880
20:26:47 7182 8192000000 10237272000 10655182848 1341722736 14.073 100 23 46 0 1353291232 6611216 4957280
20:27:56 9884 8192000000 10248283760 10650476544 1313076784 13.814 98 69 142 3 1340774784 9763216 17934784
20:29:06 4870 8192000000 10194346128 10600275968 1282633056 13.537 98 40 80 1 1299114400 7380768 9100576
20:30:13 6802 8192000000 10174371216 10580787200 1265392272 13.379 98 19 38 1 1275574704 5257984 4924448
20:31:22 34285 8192000000 10154966320 10559328256 1237160704 13.120 97 23 46 1 1247737584 4783504 5793376
20:33:00 5273 8192000000 10151264128 10554667008 1251205008 13.249 99 25 51 0 1262511968 5471728 5835232
20:34:05 6962 8192000000 10167396208 10571190272 1272732512 13.447 100 32 64 1 1286909504 6031680 8145312
20:35:13 14750 8192000000 10188907376 10592260096 1287531456 13.582 100 16 32 0 1296391888 5207456 3652976
20:36:31 21992 8192000000 10182330464 10597474304 1296455952 13.663 100 22 44 0 1306723408 5078912 5188544
20:37:55 8808 8192000000 10205231776 10613010432 1305902560 13.749 100 25 50 2 1316492384 4667296 5922528
20:39:15 30152 8192000000 10242614320 10651762688 1332794192 13.992 100 39 78 1 1349899376 8138576 8966608
20:41:02 10081 8192000000 10262868608 10676006912 1354136080 14.185 100 41 82 1 1375001664 10595568 10270016
20:42:19 32641 8192000000 10284065152 10702684160 1374159280 14.364 100 33 66 0 1395485120 13513616 7812224
20:43:54 39699 8192000000 10291667008 10718363648 1382797632 14.442 100 52 104 2 1409185600 14171424 12216544
20:45:41 13356 8192000000 10310281872 10739658752 1389898896 14.505 100 35 70 0 1425058112 26588000 8571216
20:46:59 4104 8192000000 10327192352 10749399040 1400648272 14.601 100 57 114 2 1436524432 22148272 13727888
20:48:06 11232 8192000000 10343506352 10765754368 1408076480 14.667 100 61 122 0 1450872192 27934240 14861472
20:49:22 3529 8192000000 10355311552 10775441408 1412135600 14.703 100 73 146 1 1462097376 32484064 17477712
20:50:29 2601 8192000000 10340147984 10751700992 1385820032 14.469 100 83 166 0 1445474608 38948032 20706544
20:51:46 7886 8192000000 10334521200 10749411328 1383234496 14.445 99 73 147 0 1435527184 33674944 18617744
20:53:04 3839 8192000000 10346779136 10761629696 1395169504 14.552 99 67 133 0 1440795968 29220976 16405488
20:54:14 2116 8192000000 10368110416 10779607040 1423432432 14.803 100 85 170 1 1477168192 31938208 21797552
20:55:20 12729 8192000000 10322766688 10741993472 1383819216 14.451 99 44 88 1 1429430400 33413312 12197872
20:56:36 8045 8192000000 10350271520 10773905408 1409518896 14.680 100 54 106 1 1457960160 34784624 13656640
20:57:49 4797 8192000000 10334736480 10755022848 1371457872 14.340 99 63 126 0 1433400960 45591456 16351632
20:59:00 6624 8192000000 10327347024 10741035008 1342057216 14.076 98 52 105 1 1407563424 52197920 13308288
21:00:10 19236 8192000000 10323897248 10750746624 1366698976 14.297 99 34 68 1 1417502480 41681488 9122016
21:01:48 17106 8192000000 10333053872 10759602176 1370555888 14.332 99 28 56 3 1421851536 43588224 7707424
21:03:10 7695 8192000000 10329961712 10761261056 1373636672 14.360 100 34 68 1 1435230064 51575952 10017440
21:04:20 30615 8192000000 10296287968 10738585600 1349444976 14.142 99 28 56 1 1397291296 40217552 7628768
21:05:52 20639 8192000000 10262236864 10699542528 1317663552 13.856 98 29 58 0 1354282528 29103840 7515136
21:07:16 20270 8192000000 10250705312 10710306816 1332053264 13.986 99 4 8 0 1356308432 22933888 1321280
21:08:43 97975 8192000000 10264352144 10698838016 1331087840 13.977 98 9 19 0 1340819360 8124928 1606592
21:11:22 79965 8192000000 10286546928 10730172416 1375954032 14.380 100 16 32 0 1389037088 9753552 3329504
21:13:49 86035 8192000000 10285598384 10735853568 1363676272 14.270 99 9 18 1 1379045824 13289744 2079808
21:16:16 42420 8192000000 10306609616 10743803904 1376922944 14.389 99 28 54 2 1394976144 11995840 6057360
21:18:02 120029 8192000000 10297561648 10747011072 1351797216 14.164 97 6 12 0 1369495472 15679536 2018720
21:21:07 110640 8192000000 10317299232 10760953856 1382107456 14.435 99 12 24 0 1401066704 15965648 2993600
21:24:06 85605 8192000000 10323659440 10767486976 1371506688 14.341 98 47 93 0 1391946576 9154000 11285888
21:26:48 93648 8192000000 10297103920 10754437120 1378924912 14.407 99 24 48 1 1393108064 8787264 5395888
21:29:24 50629 8192000000 10294563248 10740846592 1335564288 14.017 96 16 32 0 1346649888 7240736 3844864
21:31:15 28963 8192000000 10323368816 10758529024 1395196912 14.552 100 44 87 4 1414962672 9354416 10411344
21:32:56 32209 8192000000 10275227664 10733023232 1363481696 14.269 98 8 16 1 1372880064 7365136 2033232
21:34:36 61644 8192000000 10280338320 10734252032 1348829600 14.137 97 17 35 0 1365897712 12732848 4335264
21:36:42 71358 8192000000 10300966512 10765271040 1389009936 14.497 100 13 27 0 1400349728 8498288 2841504
21:39:00 61576 8192000000 10284975264 10756538368 1378621872 14.404 99 18 36 0 1393909856 10924192 4363792
21:41:05 37348 8192000000 10268423024 10734657536 1357323088 14.213 98 9 18 0 1366141024 7140416 1677520
21:42:54 54352 8192000000 10303339456 10763587584 1372694608 14.351 98 11 22 0 1386373424 11696064 1982752
21:44:51 46201 8192000000 10291634736 10744410112 1347867392 14.128 97 18 36 1 1362354416 10320336 4166688
21:46:38 59788 8192000000 10290371488 10741190656 1349923952 14.147 98 9 18 1 1370975840 18708448 2343440
21:48:39 62605 8192000000 10274079072 10718822400 1326426240 13.935 96 16 32 0 1338124192 8448240 3249712
21:50:45 146400 8192000000 10288412688 10752671744 1347716800 14.127 98 27 54 0 1373651440 20004032 5930608
21:54:12 84567 8192000000 10284947168 10719903744 1305518752 13.745 95 30 61 1 1322203344 9831696 6852896
21:56:37 108192 8192000000 10245410672 10694967296 1294659472 13.647 96 26 52 1 1320369888 19235904 6474512
21:59:27 59541 8192000000 10245692544 10699046912 1346214448 14.113 100 18 36 0 1359736640 9450816 4071376
22:01:40 54341 8192000000 10246566352 10696011776 1323779376 13.911 97 9 18 0 1335122176 9294416 2048384
22:03:53 78704 8192000000 10272484240 10727329792 1352054576 14.166 99 13 26 0 1367890048 12761744 3073728
22:06:26 49966 8192000000 10264700800 10725031936 1365918848 14.290 100 7 14 0 1377821984 10340896 1562240
22:08:29 36708 8192000000 10276928080 10731327488 1360313168 14.240 99 20 40 1 1381104048 16562624 4228256
22:10:08 86825 8192000000 10305105056 10773254144 1384530320 14.457 99 22 44 0 1406335088 16502448 5302320
22:12:42 140734 8192000000 10301819904 10772713472 1341132976 14.068 96 8 16 0 1361424320 18679600 1611744
22:16:05 127588 8192000000 10300030064 10753581056 1325023648 13.922 95 12 24 0 1341819888 14318528 2477712
22:19:13 61179 8192000000 10300899088 10769416192 1367654768 14.306 98 21 42 0 1387906752 15463600 4788384
22:21:24 123079 8192000000 10310458080 10778796032 1393959184 14.541 100 21 42 0 1415639632 16822320 4858128
22:24:29 38768 8192000000 10286026800 10758246400 1383135456 14.445 99 15 30 0 1393376224 6985728 3255040
22:26:09 61342 8192000000 10281313968 10747703296 1361459952 14.250 98 12 24 1 1370926000 6951952 2514096
22:28:10 54318 8192000000 10277543056 10742857728 1337610048 14.036 96 12 24 1 1350470256 10204752 2655456
22:30:57 97292 8192000000 10290699504 10751557632 1374750016 14.370 98 19 38 1 1397284032 18083984 4450032
22:33:35 66303 8192000000 10291345568 10741211136 1342867808 14.083 96 16 32 1 1358454064 11385504 4200752
22:35:41 87248 8192000000 10289624896 10731409408 1289873808 13.603 93 32 64 0 1306846048 9294768 7677472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2021 22:39:22 to 25-12-2021 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-12-2021 22:39:38 to 25-12-2021 22:39:39.

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          237723
MON$OLDEST_ACTIVE               240441
MON$OLDEST_SNAPSHOT             240441
MON$NEXT_TRANSACTION            240441
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                       2046560
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:144: 
:145: mscs-test-161.budzdorov.ru    Sat Dec 25 22:38:08 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:147: 
--- end of diff output ---

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

25.12.21 22:39:40. 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-25 22:39:43 No severe PSQL-related problems occured
25.12.21 22:39:44. End of report.