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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16645764 kB
MemAvailable 31571904 kB
Buffers 0 kB
Cached 15071272 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 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-30 13:37:07
DTS_END                         2021-11-30 13:37:07.2380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40302
TRN_ID                          238142
ATT_ID                          668
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: 9181.78 29245 1101813
customer order: creation 165.68 414 19881
customer order: refuse 14.43 327 1732
order to supplier: creation 45.44 1428 5453
order to supplier: removal 3.41 111086 409
invoice (draft): creation 45.38 3550 5446
invoice (draft): removal 51.57 866 6188
invoice accept: apply 41.90 20034 5028
invoice accept: cancel 47.05 853 5646
customer reserve: creation 3833.99 201 460079
customer reserve: removal 4631.25 65 555750
realization accept: apply 82.02 194 9842
realization accept: cancel 91.25 135 10950
payment from customer: creation 59.14 121 7097
payment from customer: removal 6.03 73 724
payment to supplier: creation 48.80 132 5856
payment to supplier: removal 3.53 155 424
service: total inventory turnovers 3.72 3622 446
service: total monetary turnovers 6.29 478 755
service: aggregate perf. data 0.86 14836 103
service: refresh index statistics 0.03 426330 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-11-2021 13:38:55 to 30-11-2021 13:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6567
TEST_TIME 2 4689
TEST_TIME 3 4188
TEST_TIME 4 4946
TEST_TIME 5 4710
TEST_TIME 6 4866
TEST_TIME 7 5214
TEST_TIME 8 5399
TEST_TIME 9 6190
TEST_TIME 10 6130
TEST_TIME 11 6084
TEST_TIME 12 6096
TEST_TIME 13 6331
TEST_TIME 14 6433
TEST_TIME 15 7120
TEST_TIME 16 7325
TEST_TIME 17 7538
TEST_TIME 18 7760
TEST_TIME 19 7996
TEST_TIME 20 8148
TEST_TIME 21 8142
TEST_TIME 22 8455
TEST_TIME 23 8639
TEST_TIME 24 8676
TEST_TIME 25 8940
TEST_TIME 26 8962
TEST_TIME 27 9641
TEST_TIME 28 10293
TEST_TIME 29 10655
TEST_TIME 30 10896
TEST_TIME 31 10827
TEST_TIME 32 10581
TEST_TIME 33 10284
TEST_TIME 34 10060
TEST_TIME 35 10178
TEST_TIME 36 10268
TEST_TIME 37 10181
TEST_TIME 38 10050
TEST_TIME 39 10061
TEST_TIME 40 9994
TEST_TIME 41 9932
TEST_TIME 42 9948
TEST_TIME 43 9940
TEST_TIME 44 9863
TEST_TIME 45 9888
TEST_TIME 46 9971
TEST_TIME 47 9918
TEST_TIME 48 9927
TEST_TIME 49 9878
TEST_TIME 50 9984
TEST_TIME 51 9885
TEST_TIME 52 9975
TEST_TIME 53 9933
TEST_TIME 54 9980
TEST_TIME 55 10084
TEST_TIME 56 10058
TEST_TIME 57 9971
TEST_TIME 58 9892
TEST_TIME 59 9758
TEST_TIME 60 9690
TEST_TIME 61 9706
TEST_TIME 62 9638
TEST_TIME 63 9587
TEST_TIME 64 9512
TEST_TIME 65 9418
TEST_TIME 66 9411
TEST_TIME 67 9448
TEST_TIME 68 9392
TEST_TIME 69 9413
TEST_TIME 70 9447
TEST_TIME 71 9461
TEST_TIME 72 9465
TEST_TIME 73 9528
TEST_TIME 74 9587
TEST_TIME 75 9591
TEST_TIME 76 9562
TEST_TIME 77 9565
TEST_TIME 78 9638
TEST_TIME 79 9690
TEST_TIME 80 9587
TEST_TIME 81 9484
TEST_TIME 82 9497
TEST_TIME 83 9447
TEST_TIME 84 9359
TEST_TIME 85 9356
TEST_TIME 86 9319
TEST_TIME 87 9318
TEST_TIME 88 9325
TEST_TIME 89 9290
TEST_TIME 90 9271
TEST_TIME 91 9240
TEST_TIME 92 9235
TEST_TIME 93 9278
TEST_TIME 94 9432
TEST_TIME 95 9406
TEST_TIME 96 9402
TEST_TIME 97 9428
TEST_TIME 98 9382
TEST_TIME 99 9316
TEST_TIME 100 9320
TEST_TIME 101 9310
TEST_TIME 102 9279
TEST_TIME 103 9217
TEST_TIME 104 9213
TEST_TIME 105 9197
TEST_TIME 106 9207
TEST_TIME 107 9186
TEST_TIME 108 9256
TEST_TIME 109 9252
TEST_TIME 110 9219
TEST_TIME 111 9220
TEST_TIME 112 9199
TEST_TIME 113 9203
TEST_TIME 114 9314
TEST_TIME 115 9271
TEST_TIME 116 9310
TEST_TIME 117 9298
TEST_TIME 118 9245
TEST_TIME 119 9193
TEST_TIME 120 9188

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-11-2021 13:38:58 to 30-11-2021 13:39:02.

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 1528156 1528156 0 0.00 0 16854 7 0 0
doc_list_biud 1528156 1528156 0 0.00 0 72928 30 0 0
sp_add_invoice_to_stock 5113 5028 85 1.66 29 255372 20035 0 85
sp_cancel_adding_invoice 5646 5646 0 0.00 4 63346 854 0 0
sp_cancel_client_order 1732 1732 0 0.00 5 9749 327 0 0
sp_cancel_customer_reserve 555750 555750 0 0.00 1 64527 66 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 3789 73 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 17637 155 0 0
sp_cancel_supplier_invoice 6191 6188 3 0.04 2 72933 867 0 3
sp_cancel_supplier_order 409 409 0 0.00 7 1041247 111086 0 0
sp_cancel_write_off 10950 10950 0 0.00 1 21756 135 0 0
sp_client_order 19881 19881 0 0.00 4 62431 415 0 0
sp_customer_reserve 460079 460079 0 0.00 0 77799 201 0 0
sp_fill_shopping_cart 30773 30773 0 0.00 1 23057 46 0 0
sp_fill_shopping_cart_clo_res 460072 460072 0 0.00 0 16976 17 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 34102 369 0 0
sp_kill_cost_storno 17744 17744 0 0.00 0 16852 60 0 0
sp_kill_qstorno_ret_qs2qd 1129047 1129047 0 0.00 0 72927 35 0 0
sp_kill_qty_storno 595537 595537 0 0.00 0 72928 75 0 0
sp_lock_selected_doc 20594 20594 0 0.00 0 7673 4 0 0
sp_make_cost_storno 27815 27815 0 0.00 0 15058 58 0 0
sp_multiply_rows_for_pdistr 27815 27815 0 0.00 0 8036 30 0 0
sp_multiply_rows_for_qdistr 19881 19881 0 0.00 0 13029 215 0 0
sp_pay_from_customer 7097 7097 0 0.00 4 18672 122 0 0
sp_pay_to_supplier 5856 5856 0 0.00 5 18710 132 0 0
sp_payment_common 12953 12953 0 0.00 1 9790 24 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 9739 294 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 18456 326 0 0
sp_qd_handle_on_reserve_upd_sts 9842 9842 0 0.00 0 14846 58 0 0
sp_reserve_write_off 9842 9842 0 0.00 7 14866 195 0 0
sp_supplier_invoice 5446 5446 0 0.00 7 87984 3551 0 0
sp_supplier_order 5453 5453 0 0.00 6 45016 1429 0 0
srv_aggregate_perf_data 103 103 0 0.00 2594 100627 14836 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1886 140831 44040 0 0
srv_find_qd_qs_mism 1054798 1054798 0 0.00 0 12936 6 0 0
srv_make_invnt_saldo 446 446 0 0.00 59 117159 3623 0 0
srv_make_money_saldo 755 755 0 0.00 1 13789 479 0 0
srv_recalc_idx_stat 4 4 0 0.00 201234 521442 426330 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5028 5028 0 0.00 0 9613 160 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 2539 108 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 10929 27 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 9937 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 6825 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1189 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 268 7 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 22302 1547 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1910 12 0 0
t$perf-norm:sp_client_order 19881 19881 0 0.00 0 10992 18 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1715 9 0 0
t$perf-norm:sp_pay_from_customer 7097 7097 0 0.00 0 8727 10 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 10854 12 0 0
t$perf-norm:sp_reserve_write_off 9842 9842 0 0.00 0 10938 13 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 8651 21 0 0
t$perf-norm:sp_supplier_order 5453 5453 0 0.00 0 5489 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 553 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 163 3 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 360 5 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 2992 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10210 10040 170 1.66 0 21345 25 0 170
v_all_customers 36046 36046 0 0.00 0 96 1 0 0
v_all_suppliers 30120 30120 0 0.00 0 42 1 0 0
v_all_wares 39762 39762 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 2410 9 0 0
v_cancel_client_order 3464 3464 0 0.00 0 3983 10 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3760 9 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 12264 15 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 17604 38 0 0
v_cancel_supplier_invoice 762 756 6 0.78 0 4088 22 0 6
v_cancel_supplier_order 818 818 0 0.00 0 4657 16 0 0
v_cancel_write_off 1930 1930 0 0.00 0 21670 47 0 0
v_max_id_clo_ord 5452 5452 0 0.00 0 1773 2 0 0
v_max_id_clo_res 974 974 0 0.00 0 11804 45 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 6110 5 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 2326 3 0 0
v_max_non_paid_realizn 3866 3866 0 0.00 0 3062 4 0 0
v_min_id_clo_ord 5452 5452 0 0.00 0 2071 5 0 0
v_min_id_clo_res 974 974 0 0.00 0 7385 19 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 6807 10 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 9786 18 0 0
v_min_non_paid_realizn 7097 7097 0 0.00 0 5378 16 0 0
v_our_firm 10178 10178 0 0.00 0 20 1 0 0
v_reserve_write_off 19684 19684 0 0.00 0 14759 11 0 0
x_lock_depdocs_on_canc_invoice 11456 11456 0 0.00 0 33621 111 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 12206 392 0 0
x_make_qstorno_1000_1200 5453 5453 0 0.00 2 45010 1350 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 3 87955 3372 0 0
x_make_qstorno_2100_3300 460079 460079 0 0.00 0 77798 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2021 13:39:02 to 30-11-2021 13:39:06.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 32680 8192000000 9738136800 10036752384 806505520 8.962 100 90 180 0 836805136 8345056 21954560
11:09:54 22607 8192000000 9768703872 10076184576 839952576 9.299 100 93 186 0 878769056 16411424 22405056
11:11:20 10637 8192000000 9812306256 10130386944 874435856 9.644 100 78 156 0 919444400 27382592 17625952
11:12:33 11969 8192000000 9862594288 10193018880 915163856 10.048 100 81 162 0 978038992 44870272 18004864
11:13:48 9591 8192000000 9915863632 10258882560 955875120 10.449 100 81 162 1 1036218032 63573088 16769824
11:15:01 3599 8192000000 9959988368 10314989568 988828080 10.770 100 74 148 1 1079628896 75582288 15218528
11:16:07 2296 8192000000 10001009616 10365689856 1019638944 11.069 100 82 162 1 1117076272 81202160 16235168
11:17:10 1965 8192000000 10012699536 10378944512 1052069584 11.381 100 90 180 2 1124412352 54037424 18305344
11:18:13 33260 8192000000 10010675328 10402086912 1069421504 11.547 100 61 122 0 1139159840 56298512 13439824
11:19:53 8168 8192000000 9988031856 10378625024 1077604560 11.625 100 39 78 0 1097900736 11935136 8361040
11:21:04 7684 8192000000 9991834368 10396520448 1081671920 11.663 100 29 57 0 1120802624 33551536 5579168
11:22:15 5488 8192000000 10000279584 10397089792 1086125584 11.706 100 35 70 0 1109334192 15330384 7878224
11:23:21 30156 8192000000 10012548864 10399588352 1089864992 11.741 100 71 144 2 1113150944 7272816 16013136
11:24:52 5384 8192000000 10002630000 10407628800 1093390416 11.775 100 37 74 0 1120437840 19326848 7720576
11:25:59 6706 8192000000 10011137904 10414178304 1082256608 11.669 99 44 89 1 1119056544 26972464 9827472
11:27:05 11733 8192000000 9976249952 10384936960 1017274608 11.046 94 22 44 0 1045750480 24304576 4171296
11:28:17 20434 8192000000 9967085456 10369818624 1050030000 11.361 99 32 63 2 1092409248 35229936 7149312
11:29:38 4881 8192000000 9961095200 10348183552 1036360192 11.230 98 48 94 1 1059052144 10914016 11777936
11:30:43 6438 8192000000 9948929200 10334691328 1016894096 11.042 98 45 87 1 1063719440 37223488 9601856
11:31:50 10177 8192000000 9935523328 10319990784 994877744 10.829 96 41 79 2 1017534048 12666208 9990096
11:33:21 16616 8192000000 9952386592 10340327424 1028508752 11.154 99 28 57 2 1051705728 16972944 6224032
11:34:38 8920 8192000000 9977647168 10371092480 1050587056 11.366 100 46 92 1 1076770800 16333120 9850624
11:35:54 10704 8192000000 9983322032 10387058688 1065542672 11.509 100 35 70 2 1104136784 30831296 7762816
11:37:10 82413 8192000000 10011752368 10409385984 1078804304 11.636 100 41 82 0 1113315936 25835008 8676624
11:39:40 8598 8192000000 10049158160 10451456000 1095491664 11.795 100 52 103 0 1153534048 47460736 10581648
11:40:56 25775 8192000000 10070450720 10470621184 1108295600 11.916 100 45 88 2 1176343856 59011264 9036992
11:42:28 14901 8192000000 10109475184 10512924672 1122847216 12.054 100 47 94 0 1212581568 79656272 10078080
11:43:52 6867 8192000000 10137070032 10543235072 1132535472 12.145 100 51 102 2 1243474960 98924096 12015392
11:45:09 12649 8192000000 10131889504 10542878720 1136492176 12.183 100 35 68 0 1243397648 99649888 7255584
11:46:29 24773 8192000000 10128331584 10555863040 1137190080 12.189 99 34 69 1 1242136752 97559136 7387536
11:47:57 12291 8192000000 10191916704 10610368512 1158279232 12.387 100 51 101 3 1290126912 120760736 11086944
11:49:13 2746 8192000000 10246127952 10644852736 1164110672 12.442 100 81 160 0 1350646832 169609696 16926464
11:50:19 6678 8192000000 10283854880 10688147456 1166632816 12.465 100 84 166 1 1392144176 207836048 17675312
11:51:30 1884 8192000000 10301776704 10704027648 1169800592 12.495 100 76 152 1 1404640048 217303568 17535888
11:52:35 4595 8192000000 10306537008 10721275904 1153195424 12.339 100 78 156 0 1400409040 228806512 18407104
11:53:44 2838 8192000000 10321637168 10724446208 1164318544 12.444 100 73 145 1 1422326544 241714672 16293328
11:54:56 9069 8192000000 10350231248 10756186112 1144494752 12.258 98 50 99 0 1429355360 273185712 11674896
11:56:08 3492 8192000000 10346556512 10772037632 1160424224 12.407 100 40 80 0 1444705936 274077376 10204336
11:57:18 8732 8192000000 10354204528 10785980416 1124523088 12.070 99 37 74 0 1443528704 309881600 9124016
11:58:28 10448 8192000000 10412775888 10836353024 1160842832 12.411 100 41 80 2 1518123920 347094464 10186624
11:59:44 5840 8192000000 10425471984 10886840320 1151233328 12.321 100 45 89 2 1559165552 396395440 11536784
12:01:16 10861 8192000000 10419389520 10870562816 1142658864 12.241 100 35 70 0 1527999184 376280096 9060224
12:02:33 10147 8192000000 10398357712 10841104384 1126906848 12.092 100 32 64 0 1515276688 379879600 8490240
12:03:46 21405 8192000000 10384085872 10853236736 1113163760 11.962 100 19 38 0 1499313552 380543712 5606080
12:05:33 29968 8192000000 10128776512 10687270912 1110569968 11.938 99 16 31 1 1304533984 190379584 3584432
12:07:09 33920 8192000000 10102951264 10546831360 1123593328 12.061 100 22 44 1 1200292624 71956544 4742752
12:08:44 22208 8192000000 10068778864 10498244608 1106783488 11.902 98 33 67 1 1143448944 28687152 7978304
12:10:11 130789 8192000000 10177489552 10623524864 1123897792 12.064 99 28 55 1 1236610576 106720000 5992784
12:13:31 39203 8192000000 10076257168 10503417856 1122263552 12.048 99 49 96 1 1181374656 48306016 10805088
12:15:12 39137 8192000000 10147192592 10592124928 1146620944 12.278 100 41 82 0 1238411088 82812960 8977184
12:17:00 60931 8192000000 10144089760 10622894080 1158007056 12.385 100 17 34 0 1240225520 78721840 3496624
12:19:09 51772 8192000000 10155534304 10642784256 1156210976 12.368 100 11 23 2 1266083104 107577904 2294224
12:21:02 45701 8192000000 10093087920 10563104768 1149978384 12.309 99 16 32 0 1218583792 65449344 3156064
12:22:48 44464 8192000000 10085574080 10577928192 1119006448 12.018 97 8 16 1 1178471536 57965552 1499536
12:24:48 36175 8192000000 10108192928 10562174976 1146769712 12.279 99 39 78 0 1196428512 41491104 8167696
12:27:00 45968 8192000000 10124797840 10614829056 1142072992 12.235 99 24 46 1 1220277104 73487888 4716224
12:28:49 63002 8192000000 10082733984 10535653376 1095511696 11.795 96 19 38 0 1157921904 58700448 3709760
12:30:54 61217 8192000000 10110010752 10592563200 1099457408 11.832 96 22 44 1 1199937248 96017552 4462288
12:32:56 70381 8192000000 10069368592 10507096064 1107855024 11.912 97 16 32 0 1136217440 24705040 3657376
12:35:10 88384 8192000000 10116262288 10542645248 1106517488 11.899 97 56 112 0 1167202320 49370400 11314432
12:37:46 56503 8192000000 10062964368 10524647424 1087363456 11.718 96 23 46 0 1159506704 67028928 5114320
12:39:46 52558 8192000000 10037767024 10502844416 1103786656 11.874 99 10 20 0 1130815200 24886304 2142240
12:41:45 66837 8192000000 10080068720 10537250816 1097332768 11.812 98 18 36 2 1142892496 42011712 3548016
12:43:53 65045 8192000000 10047078032 10486669312 1085954272 11.704 97 18 36 2 1132684544 42365344 4364928
12:45:58 41801 8192000000 10102911600 10530594816 1095965680 11.799 97 28 56 0 1187247424 84599008 6682736
12:47:44 67344 8192000000 10079222096 10547662848 1046626608 11.328 93 20 40 2 1110164992 59263136 4275248
12:49:52 102235 8192000000 10075365760 10584535040 1122064880 12.046 100 17 33 1 1222815312 96623168 4127264
12:52:34 52718 8192000000 10067103552 10500214784 1094362720 11.784 97 33 64 0 1133397408 33112544 5922144
12:54:29 12437 8192000000 10100244064 10512535552 1092875696 11.770 98 60 120 0 1169404720 64433744 12095280
12:55:43 111129 8192000000 10073683280 10559995904 1110250368 11.935 99 14 28 1 1165794848 52633344 2911136
12:58:59 38974 8192000000 10086922240 10531680256 1116680272 11.996 99 37 74 0 1185103888 60856528 7567088
13:00:39 35327 8192000000 10066450720 10566066176 1127821136 12.101 100 6 12 0 1179282000 50231120 1229744
13:02:19 49378 8192000000 10052773888 10521964544 1102471792 11.861 98 13 26 0 1128107248 23099280 2536176
13:04:20 54431 8192000000 10067228816 10550456320 1112495584 11.956 98 30 61 1 1184424832 65276608 6652640
13:06:16 47830 8192000000 10078076896 10510479360 1106643552 11.901 98 27 54 0 1146267072 33814336 5809184
13:08:11 74551 8192000000 10158395168 10598785024 1109765408 11.930 97 21 42 1 1201147744 86855616 4526720
13:10:30 79764 8192000000 10132916160 10633527296 1130453376 12.126 100 20 40 1 1267499344 132295584 4750384
13:13:05 38515 8192000000 10079970704 10545364992 1110412640 11.936 98 17 34 0 1147655616 33538960 3704016
13:14:56 67188 8192000000 10096390224 10541674496 1116152928 11.991 98 38 76 0 1173409696 49069968 8186800
13:17:08 84473 8192000000 10119473152 10579861504 1140902784 12.224 100 17 34 0 1196570688 52101936 3565968
13:19:33 34640 8192000000 10075895136 10586562560 1131774976 12.138 99 6 12 0 1181043632 48402832 865824
13:21:07 65165 8192000000 10062016032 10510802944 1102526064 11.862 96 14 27 1 1131349072 25954432 2868576
13:23:20 48050 8192000000 10074669216 10526982144 1106260544 11.897 97 25 50 0 1163509840 51458512 5790784
13:25:17 63543 8192000000 10082159664 10565922816 1131323392 12.134 99 16 32 1 1216521184 81612224 3585568
13:27:22 41419 8192000000 10092870592 10532061184 1114808304 11.978 98 33 66 2 1153895008 31859184 7227520
13:29:15 43402 8192000000 10103599584 10535874560 1086698560 11.711 96 29 57 0 1161851856 69329568 5823728
13:30:59 63711 8192000000 10066859728 10590748672 1098519792 11.824 98 19 38 0 1210220064 107598736 4101536
13:33:07 56595 8192000000 10062165456 10513137664 1108257856 11.916 99 23 46 0 1160410784 47536576 4616352
13:35:04 140830 8192000000 9293140944 9639968768 424723392 4.929 38 11 20 1 458124848 31015088 2386368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-11-2021 13:39:08 to 30-11-2021 13:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-11-2021 13:39:24 to 30-11-2021 13:39:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          238893
MON$OLDEST_ACTIVE               238894
MON$OLDEST_SNAPSHOT             238894
MON$NEXT_TRANSACTION            238894
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2048864
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:136: 
:137: mscs-test-161.budzdorov.ru    Tue Nov 30 13:37:39 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Tue Nov 30 13:37:39 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
--- end of diff output ---

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

30.11.21 13:39:27. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-30 13:39:30 No severe PSQL-related problems occured
30.11.21 13:39:31. End of report.