Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.09.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15631684 kB
MemAvailable 30758368 kB
Buffers 0 kB
Cached 16023032 kB
SwapCached 668 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.8
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-09-23 19:37:51
DTS_END                         2021-09-23 19:37:51.8080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54256
TRN_ID                          247815
ATT_ID                          692
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: 9428.71 22140 1131445
customer order: creation 176.72 332 21206
customer order: refuse 14.69 338 1763
order to supplier: creation 47.48 1256 5697
order to supplier: removal 3.58 100502 429
invoice (draft): creation 47.43 3534 5692
invoice (draft): removal 54.41 878 6529
invoice accept: apply 43.23 17179 5187
invoice accept: cancel 48.38 897 5805
customer reserve: creation 3969.66 169 476359
customer reserve: removal 4720.43 59 566451
realization accept: apply 85.28 187 10233
realization accept: cancel 85.61 108 10273
payment from customer: creation 59.65 120 7158
payment from customer: removal 5.80 69 696
payment to supplier: creation 50.51 142 6061
payment to supplier: removal 3.38 97 405
service: total inventory turnovers 4.79 1331 575
service: total monetary turnovers 6.89 47 827
service: aggregate perf. data 0.79 18802 95
service: refresh index statistics 0.03 296746 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-09-2021 19:39:07 to 23-09-2021 19:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2462
TEST_TIME 2 2153
TEST_TIME 3 3296
TEST_TIME 4 4142
TEST_TIME 5 4068
TEST_TIME 6 4238
TEST_TIME 7 4214
TEST_TIME 8 4516
TEST_TIME 9 4743
TEST_TIME 10 4934
TEST_TIME 11 5137
TEST_TIME 12 5494
TEST_TIME 13 5884
TEST_TIME 14 6363
TEST_TIME 15 6534
TEST_TIME 16 7217
TEST_TIME 17 7744
TEST_TIME 18 7725
TEST_TIME 19 7991
TEST_TIME 20 8269
TEST_TIME 21 8407
TEST_TIME 22 8379
TEST_TIME 23 8498
TEST_TIME 24 8564
TEST_TIME 25 8498
TEST_TIME 26 8672
TEST_TIME 27 8624
TEST_TIME 28 9084
TEST_TIME 29 9264
TEST_TIME 30 9271
TEST_TIME 31 9477
TEST_TIME 32 9728
TEST_TIME 33 10859
TEST_TIME 34 10746
TEST_TIME 35 10769
TEST_TIME 36 10653
TEST_TIME 37 10481
TEST_TIME 38 10308
TEST_TIME 39 10232
TEST_TIME 40 10354
TEST_TIME 41 10405
TEST_TIME 42 10421
TEST_TIME 43 10354
TEST_TIME 44 10230
TEST_TIME 45 10125
TEST_TIME 46 10233
TEST_TIME 47 10129
TEST_TIME 48 9962
TEST_TIME 49 9959
TEST_TIME 50 9951
TEST_TIME 51 9897
TEST_TIME 52 9767
TEST_TIME 53 9715
TEST_TIME 54 9642
TEST_TIME 55 9691
TEST_TIME 56 9621
TEST_TIME 57 9682
TEST_TIME 58 9694
TEST_TIME 59 9911
TEST_TIME 60 9842
TEST_TIME 61 9801
TEST_TIME 62 9727
TEST_TIME 63 9675
TEST_TIME 64 9734
TEST_TIME 65 9744
TEST_TIME 66 9698
TEST_TIME 67 9723
TEST_TIME 68 9734
TEST_TIME 69 9839
TEST_TIME 70 9799
TEST_TIME 71 9819
TEST_TIME 72 9851
TEST_TIME 73 9790
TEST_TIME 74 9781
TEST_TIME 75 9812
TEST_TIME 76 9771
TEST_TIME 77 9854
TEST_TIME 78 9795
TEST_TIME 79 9708
TEST_TIME 80 9685
TEST_TIME 81 9612
TEST_TIME 82 9638
TEST_TIME 83 9594
TEST_TIME 84 9598
TEST_TIME 85 9557
TEST_TIME 86 9494
TEST_TIME 87 9519
TEST_TIME 88 9444
TEST_TIME 89 9473
TEST_TIME 90 9449
TEST_TIME 91 9455
TEST_TIME 92 9460
TEST_TIME 93 9462
TEST_TIME 94 9439
TEST_TIME 95 9444
TEST_TIME 96 9486
TEST_TIME 97 9525
TEST_TIME 98 9519
TEST_TIME 99 9522
TEST_TIME 100 9560
TEST_TIME 101 9623
TEST_TIME 102 9619
TEST_TIME 103 9637
TEST_TIME 104 9661
TEST_TIME 105 9640
TEST_TIME 106 9581
TEST_TIME 107 9584
TEST_TIME 108 9555
TEST_TIME 109 9561
TEST_TIME 110 9545
TEST_TIME 111 9527
TEST_TIME 112 9481
TEST_TIME 113 9464
TEST_TIME 114 9423
TEST_TIME 115 9377
TEST_TIME 116 9399
TEST_TIME 117 9421
TEST_TIME 118 9436
TEST_TIME 119 9446
TEST_TIME 120 9431

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-09-2021 19:39:11 to 23-09-2021 19:39:14.

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 1573353 1573353 0 0.00 0 21825 6 0 0
doc_list_biud 1573352 1573352 0 0.00 0 97208 28 0 0
sp_add_invoice_to_stock 5244 5187 57 1.08 33 178945 17180 0 57
sp_cancel_adding_invoice 5805 5805 0 0.00 5 74589 897 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 11138 338 0 0
sp_cancel_customer_reserve 566451 566451 0 0.00 1 23886 59 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 4070 69 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 7604 97 0 0
sp_cancel_supplier_invoice 6534 6529 5 0.07 2 97221 878 0 5
sp_cancel_supplier_order 429 429 0 0.00 8 992728 100503 0 0
sp_cancel_write_off 10273 10273 0 0.00 1 8055 109 0 0
sp_client_order 21206 21206 0 0.00 3 27250 333 0 0
sp_customer_reserve 476359 476359 0 0.00 0 96111 170 0 0
sp_fill_shopping_cart 32587 32587 0 0.00 1 18953 42 0 0
sp_fill_shopping_cart_clo_res 476351 476351 0 0.00 0 29073 13 0 0
sp_get_clo_for_invoice 5178 5178 0 0.00 0 32800 268 0 0
sp_kill_cost_storno 17179 17179 0 0.00 0 13894 58 0 0
sp_kill_qstorno_ret_qs2qd 1150133 1150133 0 0.00 0 97202 32 0 0
sp_kill_qty_storno 606661 606661 0 0.00 0 97206 71 0 0
sp_lock_selected_doc 21250 21250 0 0.00 0 11027 5 0 0
sp_make_cost_storno 28630 28630 0 0.00 0 20551 66 0 0
sp_multiply_rows_for_pdistr 28630 28630 0 0.00 0 7255 29 0 0
sp_multiply_rows_for_qdistr 21206 21206 0 0.00 0 18798 158 0 0
sp_pay_from_customer 7158 7158 0 0.00 4 33235 120 0 0
sp_pay_to_supplier 6061 6061 0 0.00 5 19464 143 0 0
sp_payment_common 13219 13219 0 0.00 1 30707 40 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 7936 309 0 0
sp_qd_handle_on_invoice_upd_sts 10983 10983 0 0.00 0 21446 359 0 0
sp_qd_handle_on_reserve_upd_sts 10233 10233 0 0.00 0 18670 59 0 0
sp_reserve_write_off 10233 10233 0 0.00 6 21870 187 0 0
sp_supplier_invoice 5692 5692 0 0.00 7 85164 3534 0 0
sp_supplier_order 5697 5697 0 0.00 5 85163 1257 0 0
srv_aggregate_perf_data 95 95 0 0.00 3103 225917 18803 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2163 173214 45559 0 0
srv_find_qd_qs_mism 1082853 1082853 0 0.00 0 14937 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 37 148296 1331 0 0
srv_make_money_saldo 827 827 0 0.00 1 9447 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 97829 395803 296747 0 0
t$perf-abend:sp_add_invoice_to_stock 57 57 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5187 5187 0 0.00 0 17127 176 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 12866 154 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 2303 19 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 6872 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 5699 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 496 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 6381 38 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 26226 1773 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 2026 6 0 0
t$perf-norm:sp_client_order 21206 21206 0 0.00 0 8188 19 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 4506 11 0 0
t$perf-norm:sp_pay_from_customer 7158 7158 0 0.00 0 11046 16 0 0
t$perf-norm:sp_pay_to_supplier 6061 6061 0 0.00 0 11315 15 0 0
t$perf-norm:sp_reserve_write_off 10233 10233 0 0.00 0 13927 17 0 0
t$perf-norm:sp_supplier_invoice 5692 5692 0 0.00 0 14270 27 0 0
t$perf-norm:sp_supplier_order 5697 5697 0 0.00 0 11155 22 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 288 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 94 3 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1964 27 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 1709 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10470 10356 114 1.08 0 5844 17 0 114
v_all_customers 38154 38154 0 0.00 0 116 1 0 0
v_all_suppliers 31340 31340 0 0.00 0 117 1 0 0
v_all_wares 42412 42412 0 0.00 0 89 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 322 6 0 0
v_cancel_client_order 3526 3526 0 0.00 0 1857 4 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 2652 6 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 306 3 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 520 5 0 0
v_cancel_supplier_invoice 820 810 10 1.21 0 1707 17 0 10
v_cancel_supplier_order 858 858 0 0.00 0 879 4 0 0
v_cancel_write_off 2024 2024 0 0.00 0 8012 20 0 0
v_max_id_clo_ord 5694 5694 0 0.00 0 763 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 4672 19 0 0
v_max_id_ord_sup 5686 5686 0 0.00 0 7466 5 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 1428 4 0 0
v_max_non_paid_realizn 3982 3982 0 0.00 0 7539 6 0 0
v_min_id_clo_ord 5694 5694 0 0.00 0 4988 8 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 6165 9 0 0
v_min_id_ord_sup 5686 5686 0 0.00 0 5383 7 0 0
v_min_non_paid_invoice 6061 6061 0 0.00 0 17686 30 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 17200 24 0 0
v_our_firm 10610 10610 0 0.00 0 85 1 0 0
v_reserve_write_off 20466 20466 0 0.00 0 4643 5 0 0
x_lock_depdocs_on_canc_invoice 11929 11929 0 0.00 0 25882 127 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 7702 361 0 0
x_make_qstorno_1000_1200 5697 5697 0 0.00 2 85159 1165 0 0
x_make_qstorno_1200_2000 5692 5692 0 0.00 3 85151 3386 0 0
x_make_qstorno_2100_3300 476359 476359 0 0.00 0 96109 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2021 19:39:15 to 23-09-2021 19:39:19.

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:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:56 17500 8192000000 10376440416 10698485760 1437523904 14.928 100 87 174 1 1471765424 5809040 28432480
17:10:22 18559 8192000000 10433923440 10766295040 1492426592 15.410 100 82 164 0 1526621568 7563552 26631424
17:11:44 25204 8192000000 10499086896 10841903104 1551856464 15.926 100 84 168 2 1587471296 9327280 26287552
17:13:14 10869 8192000000 10578575456 10933952512 1629073200 16.587 100 78 156 0 1662724416 10254016 23397200
17:14:34 9542 8192000000 10652246016 11019055104 1709980096 17.269 100 79 158 0 1744534800 12050256 22504448
17:15:48 4547 8192000000 10709962944 11089268736 1768665456 17.756 100 82 164 2 1806281872 14308576 23307840
17:16:54 6208 8192000000 10753541376 11137978368 1813557120 18.125 100 88 176 0 1852174144 15080272 23536752
17:18:02 2267 8192000000 10800925200 11186266112 1859553888 18.500 100 87 174 0 1899121616 14369680 25198048
17:19:05 4353 8192000000 10803563568 11214876672 1874240080 18.619 100 57 113 0 1903809152 13915360 15653712
17:20:13 23456 8192000000 10818741920 11223916544 1886760704 18.720 100 72 147 3 1914084368 6735600 20588064
17:21:37 3686 8192000000 10814151856 11237580800 1890957040 18.753 100 41 82 1 1913762736 11502560 11303136
17:22:44 7714 8192000000 10808794736 11235188736 1893095760 18.771 100 15 30 0 1903463856 6051920 4316176
17:23:54 5358 8192000000 10821125808 11253751808 1910079104 18.907 100 21 42 1 1926140640 10161104 5900432
17:25:01 30819 8192000000 10831676688 11261452288 1915443392 18.950 100 28 57 1 1929896848 5911264 8542192
17:26:33 5685 8192000000 10829051360 11260760064 1912418944 18.926 100 29 58 2 1928468208 6949232 9100032
17:27:41 6141 8192000000 10792104016 11221274624 1867945632 18.568 99 28 57 0 1882129856 6428528 7755696
17:28:48 79913 8192000000 10712926816 11128225792 1608089808 16.408 86 27 54 1 1620621984 4419904 8112272
17:31:09 6422 8192000000 10693190912 11104845824 1774049520 17.800 100 30 59 1 1788817184 5945584 8822080
17:32:18 5885 8192000000 10715481312 11136483328 1789296688 17.926 99 18 36 0 1800431856 5664208 5470960
17:33:25 78294 8192000000 10744503232 11175862272 1820185488 18.179 99 33 66 0 1834964160 4838512 9940160
17:35:47 3602 8192000000 10784164736 11207585792 1865408800 18.547 100 34 67 3 1880558624 4785808 10364016
17:36:51 5510 8192000000 10787138576 11218472960 1872135072 18.602 100 19 38 1 1884277248 6480720 5661456
17:37:58 36905 8192000000 10832825264 11252338688 1903766784 18.857 100 28 55 0 1916899744 5521728 7611232
17:39:39 105111 8192000000 10856118352 11289395200 1908231472 18.892 99 30 60 1 1926528448 8839040 9457936
17:42:32 11614 8192000000 10886795200 11311312896 1946851760 19.201 100 57 112 0 1973978016 11334400 15791856
17:43:48 13999 8192000000 10899925392 11329769472 1964453136 19.341 100 55 108 2 1993957072 12799792 16704144
17:45:11 16222 8192000000 10907697280 11336540160 1954210928 19.260 99 52 103 0 1983986496 13977760 15797808
17:46:32 2523 8192000000 10928021424 11358093312 1986681888 19.518 100 65 128 2 2024097328 17805088 19610352
17:47:41 16837 8192000000 10915706704 11351166976 1964704592 19.343 99 42 84 0 1994792544 17070288 13017664
17:49:18 7874 8192000000 10945891408 11364470784 1988038720 19.528 100 78 154 3 2032807856 21864880 22904256
17:50:30 3357 8192000000 10928726752 11347791872 1965573888 19.350 100 63 126 0 2009499552 24891184 19034480
17:51:37 3456 8192000000 10928986288 11349540864 1964541568 19.342 100 67 134 0 2011102896 25865376 20695952
17:52:46 2162 8192000000 10936420080 11345473536 1953891760 19.257 100 85 170 1 2016872176 36598096 26382320
17:53:52 11230 8192000000 10908703904 11341811712 1947284624 19.205 100 51 102 3 1994688704 31380960 16023120
17:55:16 2937 8192000000 10891494992 11313811456 1919932384 18.986 99 65 130 2 1965662352 26620304 19109664
17:56:25 4960 8192000000 10876276976 11299495936 1888340272 18.732 98 58 119 1 1937063344 30993056 17730016
17:57:38 3233 8192000000 10920060480 11326582784 1936120752 19.116 100 92 185 1 1997866720 33796640 27949328
17:58:45 3210 8192000000 10920841136 11337412608 1942593104 19.167 100 67 134 2 1999668384 36113984 20961296
17:59:53 5252 8192000000 10928360320 11358736384 1958344432 19.293 100 49 96 1 2011342896 37944544 15053920
18:01:05 6757 8192000000 10937010192 11376336896 1957109024 19.283 99 31 62 0 2008829744 40945056 10775664
18:02:16 2398 8192000000 10977142560 11380928512 1978017184 19.449 100 84 167 1 2046470096 42070288 26382624
18:03:22 6916 8192000000 10967825312 11398344704 1982626416 19.485 100 44 87 1 2047513872 49944032 14943424
18:04:40 7065 8192000000 10979226496 11407970304 1981371904 19.476 100 53 106 2 2052377776 53756592 17249280
18:05:49 13690 8192000000 10971885504 11418484736 1990349568 19.547 100 38 74 1 2056238144 53153200 12735376
18:07:15 17111 8192000000 10951678832 11396124672 1970496544 19.389 100 28 56 2 2034283488 53087584 10699360
18:08:37 40541 8192000000 10967439392 11427414016 1984706096 19.502 100 27 54 1 2057761344 63209936 9845312
18:10:38 39272 8192000000 10902305616 11365318656 1954336464 19.261 99 13 26 3 1983380288 25477232 3566592
18:12:19 82066 8192000000 10905975264 11381178368 1955720144 19.272 99 7 12 2 1973420336 16027520 1672672
18:14:57 92227 8192000000 10930715984 11379863552 1952791200 19.249 98 21 42 1 1970232624 11941712 5499712
18:17:30 92200 8192000000 10915000944 11393753088 1976389216 19.436 100 8 14 0 1994006512 15481072 2136224
18:20:03 77642 8192000000 10894999040 11350765568 1903362448 18.853 96 8 14 3 1913854832 8839232 1653152
18:22:29 23619 8192000000 10934157680 11369799680 1958010384 19.290 99 59 118 1 1992301600 17512224 16778992
18:23:53 150689 8192000000 10876596336 11321417728 1863285760 18.530 95 13 26 0 1885807792 18707568 3814464
18:27:25 37709 8192000000 10921835232 11350773760 1925634800 19.032 98 27 54 0 1941766192 8245232 7886160
18:29:05 88936 8192000000 10918168544 11384811520 1961880064 19.321 99 30 57 2 1986594864 16404928 8309872
18:31:35 40767 8192000000 10911331504 11369988096 1939743888 19.145 99 26 51 0 1971627504 24065584 7818032
18:33:29 61496 8192000000 10911129744 11363033088 1892981984 18.770 96 42 85 2 1937665808 31360032 13323792
18:36:07 105297 8192000000 10834352256 11298852864 1778870864 17.840 91 20 40 0 1795510144 10542720 6096560
18:38:53 75088 8192000000 10846686320 11322327040 1872814144 18.607 97 10 20 0 1884495376 9261568 2419664
18:41:08 43869 8192000000 10878342912 11344302080 1940709360 19.152 100 20 40 1 1958417696 11915696 5792640
18:42:52 48620 8192000000 10860642352 11321696256 1866404352 18.555 96 15 30 0 1880953120 10579920 3968848
18:44:41 37178 8192000000 10870306112 11339923456 1919130320 18.980 99 23 46 0 1942779504 17169712 6479472
18:46:23 52886 8192000000 10876030160 11331493888 1933587552 19.096 100 19 38 0 1955734144 16427328 5719264
18:48:19 51833 8192000000 10884052720 11357687808 1934511808 19.103 99 24 48 0 1954533328 13197696 6823824
18:50:38 18856 8192000000 10911678432 11359387648 1960797008 19.312 100 51 103 2 1983829136 8658336 14373792
18:51:57 94473 8192000000 10903300304 11369074688 1926236800 19.037 98 11 22 0 1941205904 11792192 3176912
18:54:32 173213 8192000000 10894139744 11361071104 1928467072 19.055 98 6 11 1 1948755696 18833824 1454800
18:58:26 26110 8192000000 10914344960 11380686848 1983023392 19.489 100 30 61 1 2001017712 9519456 8474864
18:59:53 44588 8192000000 10876433824 11353489408 1916250928 18.957 97 17 35 0 1931582384 10641920 4689536
19:01:38 40686 8192000000 10896431456 11370704896 1974234640 19.419 100 11 22 0 1986470608 9339616 2896352
19:03:20 61358 8192000000 10919352976 11395391488 1974408048 19.420 99 17 34 0 1988022080 8771008 4843024
19:05:22 88144 8192000000 10902991440 11393216512 1979689360 19.462 99 19 38 0 1995801248 10317424 5794464
19:08:05 76430 8192000000 10907339776 11404431360 1925423392 19.030 96 8 16 0 1936358960 8997056 1938512
19:10:23 55180 8192000000 10915334224 11389722624 1930160368 19.068 97 11 22 0 1942409760 9423760 2825632
19:12:19 29478 8192000000 10930354368 11406368768 1987670608 19.525 99 19 38 0 2005845840 12882416 5292816
19:14:37 38685 8192000000 10910538448 11367612416 1914870736 18.946 96 35 70 1 1932744480 7962112 9911632
19:16:23 54052 8192000000 10917586288 11375206400 1886232816 18.715 95 33 66 1 1912292544 16751680 9308048
19:18:18 86087 8192000000 10944577536 11425173504 1961638816 19.319 99 14 28 1 1995396704 29991664 3766224
19:20:49 41758 8192000000 10892145616 11365056512 1915040608 18.947 97 13 26 0 1931458544 13107632 3310304
19:22:32 34181 8192000000 10907909744 11367452672 1956504784 19.278 99 40 80 1 1983476624 16106480 10865360
19:24:17 108582 8192000000 10869968624 11350962176 1851296880 18.433 94 17 34 0 1869387376 13170112 4920384
19:27:41 72393 8192000000 10816997152 11296555008 1815416128 18.140 94 16 31 0 1827443712 8041376 3986208
19:30:19 70683 8192000000 10865809744 11340505088 1904337792 18.861 98 12 24 1 1915740128 7972544 3429792
19:33:09 25925 8192000000 10868123264 11345432576 1939897200 19.146 100 22 44 1 1964444656 17621776 6925680
19:34:35 70312 8192000000 10852940128 11337990144 1902618864 18.847 98 8 16 1 1915497184 10749184 2129136
19:36:50 57811 8192000000 10837300400 11303305216 1842896384 18.364 95 13 26 0 1854959632 8878592 3184656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-09-2021 19:39:21 to 23-09-2021 19:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 57 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2021 19:39:37 to 23-09-2021 19: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          246901
MON$OLDEST_ACTIVE               248044
MON$OLDEST_SNAPSHOT             248044
MON$NEXT_TRANSACTION            248044
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                       2077664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:151: 
:152: mscs-test-161.budzdorov.ru    Thu Sep 23 19:37:57 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
:155: 
:156: mscs-test-161.budzdorov.ru    Thu Sep 23 19:37:58 2021
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:158: 
:159: 
:160: mscs-test-161.budzdorov.ru    Thu Sep 23 19:37:58 2021
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:162: 
--- end of diff output ---

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

23.09.21 19: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-09-23 19:39:42 No severe PSQL-related problems occured
23.09.21 19:39:44. End of report.