Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 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 16443556 kB
MemAvailable 31414224 kB
Buffers 0 kB
Cached 15298380 kB
SwapCached 2544 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.2
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 2022-05-26 13:37:18
DTS_END                         2022-05-26 13:37:18.0950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55052
TRN_ID                          217129
ATT_ID                          592
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: 8864.23 28216 1063707
customer order: creation 159.23 427 19108
customer order: refuse 13.91 388 1669
order to supplier: creation 44.50 1377 5340
order to supplier: removal 3.22 178142 386
invoice (draft): creation 44.35 3049 5322
invoice (draft): removal 49.12 1072 5894
invoice accept: apply 40.64 27664 4877
invoice accept: cancel 44.59 1193 5351
customer reserve: creation 3808.03 262 456964
customer reserve: removal 4378.72 102 525446
realization accept: apply 79.60 245 9552
realization accept: cancel 73.78 165 8854
payment from customer: creation 56.13 167 6736
payment from customer: removal 5.29 152 635
payment to supplier: creation 47.26 174 5671
payment to supplier: removal 3.53 115 424
service: total inventory turnovers 3.98 3764 478
service: total monetary turnovers 7.33 357 880
service: aggregate perf. data 0.97 13818 116
service: refresh index statistics 0.03 331685 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-05-2022 13:38:51 to 26-05-2022 13:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10498
TEST_TIME 2 7049
TEST_TIME 3 6919
TEST_TIME 4 6600
TEST_TIME 5 6768
TEST_TIME 6 6617
TEST_TIME 7 6381
TEST_TIME 8 6214
TEST_TIME 9 6414
TEST_TIME 10 6547
TEST_TIME 11 6818
TEST_TIME 12 6941
TEST_TIME 13 6941
TEST_TIME 14 7233
TEST_TIME 15 7478
TEST_TIME 16 7570
TEST_TIME 17 7620
TEST_TIME 18 7748
TEST_TIME 19 7907
TEST_TIME 20 7898
TEST_TIME 21 7859
TEST_TIME 22 8006
TEST_TIME 23 8358
TEST_TIME 24 8823
TEST_TIME 25 8834
TEST_TIME 26 8872
TEST_TIME 27 9129
TEST_TIME 28 9556
TEST_TIME 29 9874
TEST_TIME 30 10136
TEST_TIME 31 10534
TEST_TIME 32 10886
TEST_TIME 33 11143
TEST_TIME 34 10982
TEST_TIME 35 11054
TEST_TIME 36 11004
TEST_TIME 37 10819
TEST_TIME 38 10616
TEST_TIME 39 10401
TEST_TIME 40 10307
TEST_TIME 41 10202
TEST_TIME 42 10101
TEST_TIME 43 10110
TEST_TIME 44 10127
TEST_TIME 45 9996
TEST_TIME 46 9858
TEST_TIME 47 9944
TEST_TIME 48 9822
TEST_TIME 49 9792
TEST_TIME 50 9815
TEST_TIME 51 9829
TEST_TIME 52 9819
TEST_TIME 53 9772
TEST_TIME 54 9763
TEST_TIME 55 9783
TEST_TIME 56 9828
TEST_TIME 57 9752
TEST_TIME 58 9777
TEST_TIME 59 9890
TEST_TIME 60 9859
TEST_TIME 61 9812
TEST_TIME 62 9720
TEST_TIME 63 9738
TEST_TIME 64 9637
TEST_TIME 65 9599
TEST_TIME 66 9525
TEST_TIME 67 9434
TEST_TIME 68 9391
TEST_TIME 69 9373
TEST_TIME 70 9408
TEST_TIME 71 9407
TEST_TIME 72 9393
TEST_TIME 73 9340
TEST_TIME 74 9313
TEST_TIME 75 9280
TEST_TIME 76 9431
TEST_TIME 77 9488
TEST_TIME 78 9512
TEST_TIME 79 9528
TEST_TIME 80 9586
TEST_TIME 81 9548
TEST_TIME 82 9445
TEST_TIME 83 9373
TEST_TIME 84 9421
TEST_TIME 85 9420
TEST_TIME 86 9368
TEST_TIME 87 9353
TEST_TIME 88 9296
TEST_TIME 89 9246
TEST_TIME 90 9203
TEST_TIME 91 9196
TEST_TIME 92 9159
TEST_TIME 93 9155
TEST_TIME 94 9148
TEST_TIME 95 9140
TEST_TIME 96 9105
TEST_TIME 97 9082
TEST_TIME 98 9097
TEST_TIME 99 9090
TEST_TIME 100 9126
TEST_TIME 101 9183
TEST_TIME 102 9221
TEST_TIME 103 9241
TEST_TIME 104 9294
TEST_TIME 105 9278
TEST_TIME 106 9259
TEST_TIME 107 9234
TEST_TIME 108 9189
TEST_TIME 109 9131
TEST_TIME 110 9065
TEST_TIME 111 9073
TEST_TIME 112 9030
TEST_TIME 113 9017
TEST_TIME 114 9004
TEST_TIME 115 9018
TEST_TIME 116 9013
TEST_TIME 117 8981
TEST_TIME 118 8962
TEST_TIME 119 8941
TEST_TIME 120 8888

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-05-2022 13:38:55 to 26-05-2022 13:38:58.

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 1482683 1482683 0 0.00 0 29213 14 0 0
doc_list_biud 1482675 1482675 0 0.00 0 37899 42 0 0
sp_add_invoice_to_stock 4977 4877 100 2.00 32 221072 27664 0 100
sp_cancel_adding_invoice 5351 5351 0 0.00 7 90550 1194 0 0
sp_cancel_client_order 1669 1669 0 0.00 6 11463 389 0 0
sp_cancel_customer_reserve 525446 525446 0 0.00 1 37924 103 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 4 8453 152 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 4 1383 115 0 0
sp_cancel_supplier_invoice 5906 5894 12 0.20 3 36484 1072 0 12
sp_cancel_supplier_order 386 386 0 0.00 7 1312541 178142 0 0
sp_cancel_write_off 8854 8854 0 0.00 2 7652 166 0 0
sp_client_order 19108 19108 0 0.00 5 29800 427 0 0
sp_customer_reserve 456964 456964 0 0.00 1 146122 263 0 0
sp_fill_shopping_cart 29761 29761 0 0.00 1 34173 80 0 0
sp_fill_shopping_cart_clo_res 456915 456915 0 0.00 0 19550 19 0 0
sp_get_clo_for_invoice 4827 4827 0 0.00 0 25268 299 0 0
sp_kill_cost_storno 15264 15264 0 0.00 0 29200 74 0 0
sp_kill_qstorno_ret_qs2qd 1066026 1066026 0 0.00 0 37822 45 0 0
sp_kill_qty_storno 561979 561979 0 0.00 0 37896 103 0 0
sp_lock_selected_doc 19930 19930 0 0.00 0 10400 8 0 0
sp_make_cost_storno 26786 26786 0 0.00 0 10703 74 0 0
sp_multiply_rows_for_pdistr 26785 26785 0 0.00 0 6727 35 0 0
sp_multiply_rows_for_qdistr 19108 19108 0 0.00 0 10326 165 0 0
sp_pay_from_customer 6736 6736 0 0.00 5 10353 168 0 0
sp_pay_to_supplier 5671 5671 0 0.00 6 13959 174 0 0
sp_payment_common 12406 12406 0 0.00 2 13942 42 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 11451 319 0 0
sp_qd_handle_on_invoice_upd_sts 10178 10178 0 0.00 0 32259 497 0 0
sp_qd_handle_on_reserve_upd_sts 9552 9552 0 0.00 0 12158 54 0 0
sp_reserve_write_off 9552 9552 0 0.00 8 12222 245 0 0
sp_supplier_invoice 5322 5322 0 0.00 11 81273 3049 0 0
sp_supplier_order 5340 5340 0 0.00 8 43844 1378 0 0
srv_aggregate_perf_data 116 116 0 0.00 2462 134718 13818 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 785 173123 37757 0 0
srv_find_qd_qs_mism 1015610 1015610 0 0.00 0 15161 12 0 0
srv_make_invnt_saldo 478 478 0 0.00 64 55540 3765 0 0
srv_make_money_saldo 880 880 0 0.00 1 12413 358 0 0
srv_recalc_idx_stat 4 4 0 0.00 261983 428199 331686 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4879 4879 0 0.00 0 40586 920 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 2 6022 383 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2600 27 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1238 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2767 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 6057 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 861 35 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 1 86377 7346 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 394 16 0 0
t$perf-norm:sp_client_order 19108 19108 0 0.00 0 11705 35 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3388 16 0 0
t$perf-norm:sp_pay_from_customer 6736 6736 0 0.00 0 7952 23 0 0
t$perf-norm:sp_pay_to_supplier 5671 5671 0 0.00 0 21318 27 0 0
t$perf-norm:sp_reserve_write_off 9552 9552 0 0.00 0 10489 27 0 0
t$perf-norm:sp_supplier_invoice 5322 5322 0 0.00 0 16993 38 0 0
t$perf-norm:sp_supplier_order 5340 5340 0 0.00 0 6374 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 185 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 45 4 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 3816 27 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 3010 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 11 146 54 0 0
v_add_invoice_to_stock 9848 9648 200 2.03 0 5583 24 0 200
v_all_customers 34990 34990 0 0.00 0 68 3 0 0
v_all_suppliers 29692 29692 0 0.00 0 96 4 0 0
v_all_wares 38210 38210 0 0.00 0 66 3 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2404 19 0 0
v_cancel_client_order 3338 3338 0 0.00 0 4972 11 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 4499 19 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 551 9 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1197 11 0 0
v_cancel_supplier_invoice 834 810 24 2.87 0 5728 25 0 24
v_cancel_supplier_order 772 772 0 0.00 0 4680 20 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2803 25 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 646 4 0 0
v_max_id_clo_res 983 983 0 0.00 0 1632 12 0 0
v_max_id_ord_sup 5315 5315 0 0.00 0 5799 8 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 291 7 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 862 6 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 6206 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 5897 18 0 0
v_min_id_ord_sup 5315 5315 0 0.00 0 5858 8 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 13933 16 0 0
v_min_non_paid_realizn 6736 6736 0 0.00 0 5654 15 0 0
v_our_firm 9610 9610 0 0.00 0 137 4 0 0
v_reserve_write_off 19102 19102 0 0.00 0 5760 10 0 0
x_lock_depdocs_on_canc_invoice 10840 10840 0 0.00 0 32979 125 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 6309 354 0 0
x_make_qstorno_1000_1200 5340 5340 0 0.00 3 43811 1211 0 0
x_make_qstorno_1200_2000 5322 5322 0 0.00 5 81261 2834 0 0
x_make_qstorno_2100_3300 456964 456964 0 0.00 0 146116 232 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-05-2022 13:38:59 to 26-05-2022 13:39:04.

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:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 21469 8192000000 9718317792 10016460800 791561808 8.811 100 87 174 0 820657888 7509600 21586480
11:09:48 30346 8192000000 9759521952 10069139456 829995776 9.199 100 89 178 0 863624784 12100624 21528384
11:11:22 16736 8192000000 9809079504 10132107264 871152896 9.612 100 87 172 0 916596064 24833008 20610160
11:12:41 8819 8192000000 9867009152 10203353088 925748112 10.153 100 82 162 0 980710624 36428320 18534192
11:13:52 4991 8192000000 9916075168 10259451904 965948528 10.547 100 95 182 0 1029518704 44747792 18822384
11:15:01 2249 8192000000 9964840336 10324008960 997689136 10.856 100 87 172 1 1078158208 61973856 18495216
11:16:05 2158 8192000000 9993775824 10357727232 1021854928 11.090 100 78 151 0 1116080816 79614160 14611728
11:17:08 913 8192000000 10028911008 10403557376 1051148272 11.372 100 89 163 1 1150669168 83078352 16442544
11:18:11 1338 8192000000 10047379776 10420248576 1065435552 11.508 100 95 174 0 1160228064 77540288 17252224
11:19:13 959 8192000000 10052471264 10430869504 1074879600 11.599 100 97 190 1 1175187184 81488448 18819136
11:20:15 3483 8192000000 10021459504 10399055872 1078715888 11.635 100 93 179 0 1142624544 46055968 17852688
11:21:20 2494 8192000000 10053901568 10446389248 1079342432 11.641 100 75 140 1 1173792768 80168592 14281744
11:22:24 1060 8192000000 10048631600 10441809920 1091488528 11.757 100 87 164 2 1171926896 63569120 16869248
11:23:26 2981 8192000000 10045550800 10440183808 1092832640 11.770 100 72 135 2 1169829488 64863728 12133120
11:24:30 1306 8192000000 10039449600 10427502592 1092309472 11.765 100 86 158 2 1161728432 55167824 14251136
11:25:32 1006 8192000000 10056819232 10446708736 1098806048 11.826 100 96 187 0 1184461408 66540304 19115056
11:26:34 1066 8192000000 10065077216 10458390528 1093878112 11.780 100 94 181 2 1187107392 74009632 19219648
11:27:35 1814 8192000000 10064617536 10457210880 1100579296 11.843 100 78 153 2 1185812224 69458352 15774576
11:28:39 982 8192000000 10061291072 10445193216 1088592672 11.729 100 93 186 1 1179679152 72005136 19081344
11:29:41 2700 8192000000 10034661056 10428968960 1091122704 11.753 100 70 130 1 1153647824 49571072 12954048
11:30:45 1670 8192000000 10046869312 10435502080 1085046288 11.696 100 81 156 3 1164277200 62961728 16269184
11:31:48 6670 8192000000 10017693808 10407768064 1063968880 11.494 99 65 128 2 1123723952 46870688 12884384
11:32:56 2501 8192000000 10012770256 10392702976 1051681584 11.377 99 75 135 0 1140192736 75419328 13091824
11:33:59 1376 8192000000 9972996112 10347454464 1029414592 11.163 99 84 163 2 1093513888 45980688 18118608
11:35:04 1679 8192000000 10005621648 10384633856 1041802800 11.282 100 80 149 3 1122085184 65416560 14865824
11:36:06 977 8192000000 9989954304 10366054400 1037850880 11.244 99 75 144 1 1112374352 61287264 13236208
11:37:08 1219 8192000000 9978426336 10356084736 1041057200 11.275 100 83 165 1 1103874896 44583056 18234640
11:38:13 3006 8192000000 10022321280 10407661568 1057153584 11.429 100 91 176 4 1147952016 72853760 17944672
11:39:24 11728 8192000000 10054466976 10437058560 1072194160 11.573 100 70 134 1 1167721024 82176384 13350480
11:40:43 1298 8192000000 10051850288 10434473984 1085822128 11.703 100 83 149 2 1173351440 72281408 15247904
11:41:46 1382 8192000000 10101406208 10493784064 1102707712 11.863 100 81 159 1 1224431744 103184864 18539168
11:42:56 2613 8192000000 10106615728 10498969600 1112992672 11.961 100 82 157 1 1224576400 94416576 17167152
11:44:02 2713 8192000000 10145505808 10541158400 1124427888 12.069 100 77 148 0 1265065408 125204080 15433440
11:45:09 1769 8192000000 10189085344 10582323200 1132214608 12.142 100 87 159 0 1303783584 154049888 17519088
11:46:16 3387 8192000000 10203293328 10616107008 1148134576 12.292 100 71 135 1 1319162224 155589536 15438112
11:47:23 1923 8192000000 10257689520 10666856448 1166663120 12.466 100 87 170 0 1371840208 186668208 18508880
11:48:29 1666 8192000000 10295180496 10698326016 1168939856 12.487 100 85 170 1 1402691680 214788400 18963424
11:49:34 950 8192000000 10330305632 10729455616 1173279552 12.527 100 98 190 1 1441742000 247781120 20681328
11:50:39 1642 8192000000 10323056592 10742919168 1180932592 12.599 100 87 166 1 1434556320 234451184 19172544
11:51:47 4000 8192000000 10346949712 10764144640 1187838608 12.663 100 77 148 0 1460138272 255145216 17154448
11:52:54 1312 8192000000 10390562752 10815266816 1180399488 12.594 100 94 186 1 1494468144 292781344 21287312
11:53:59 1093 8192000000 10393658128 10815131648 1187438048 12.660 100 93 179 2 1503520864 293957712 22125104
11:55:04 1019 8192000000 10414838016 10839351296 1190977728 12.692 100 92 184 1 1523185648 309792176 22415744
11:56:08 1288 8192000000 10453831520 10872848384 1203048176 12.805 100 92 174 1 1567058144 341801024 22208944
11:57:12 1901 8192000000 10488799328 10895740928 1197745296 12.755 100 95 188 1 1592465296 372283104 22436896
11:58:18 778 8192000000 10523368688 10948599808 1196934688 12.748 100 83 160 2 1631452480 414216480 20301312
11:59:24 857 8192000000 10565737936 11007090688 1190864704 12.691 100 92 181 0 1671421536 456636848 23919984
12:00:27 4114 8192000000 10518791744 11016880128 1200914384 12.785 100 75 150 1 1625536448 404580560 20041504
12:01:35 3109 8192000000 10514164736 10955993088 1204515072 12.818 100 66 131 0 1618021328 396681088 16825168
12:02:40 11785 8192000000 10537119440 10979938304 1198919808 12.766 100 49 98 1 1628573680 416500912 13152960
12:04:01 12694 8192000000 10542656448 11011276800 1186132304 12.647 100 44 86 0 1658729264 460889904 11707056
12:05:19 15033 8192000000 10447478304 10920026112 1182946800 12.618 100 42 82 0 1547922096 354479520 10495776
12:06:40 51010 8192000000 10362141408 10859872256 1179193072 12.583 100 30 60 2 1483345072 296550544 7601456
12:08:45 35555 8192000000 10248540976 10780508160 1129700912 12.119 97 11 22 1 1332189616 199648992 2839712
12:10:26 63521 8192000000 10105883200 10547163136 1106529968 11.900 96 20 35 1 1181880976 71315104 4035904
12:12:31 88482 8192000000 10068186032 10531315712 1065440496 11.509 93 22 41 0 1103647792 34018304 4188992
12:15:00 129881 8192000000 10117664288 10565267456 1124871456 12.073 99 41 78 1 1207837280 73755664 9210160
12:18:40 8625 8192000000 10103842496 10538917888 1143098512 12.245 100 58 111 1 1196114816 41316944 11699360
12:19:50 16457 8192000000 10125140608 10545901568 1127250112 12.095 99 58 113 0 1211946528 73094208 11602208
12:21:10 173101 8192000000 10156059408 10602065920 1053953712 11.399 93 18 36 1 1126164848 68447056 3764080
12:26:19 53549 8192000000 10097749632 10581028864 1126762832 12.091 100 10 20 0 1218874176 89727600 2383744
12:28:17 18465 8192000000 10084526640 10508849152 1129002816 12.112 100 51 96 4 1166162704 26831072 10328816
12:29:37 15420 8192000000 10105213488 10538680320 1124757232 12.072 99 55 105 1 1183369920 48370208 10242480
12:31:02 33156 8192000000 10139538256 10595938304 1112750048 11.958 98 44 88 1 1219491328 97570480 9170800
12:32:40 46466 8192000000 10058454528 10501877760 1112320944 11.954 98 19 36 2 1199589104 83521872 3746288
12:34:27 26759 8192000000 10127657760 10554847232 1125518064 12.079 99 49 88 4 1206884896 71153728 10213104
12:36:01 108719 8192000000 10067539920 10518495232 1072819264 11.579 95 12 23 0 1108462928 33266816 2376848
12:38:56 101232 8192000000 10070947744 10540834816 1107817344 11.912 98 34 67 1 1206647312 91067088 7762880
12:41:43 5943 8192000000 10073048928 10527477760 1126277824 12.086 100 59 112 0 1168493760 30661392 11554544
12:42:49 73671 8192000000 10090954064 10547691520 1124472624 12.069 99 15 30 0 1194760064 67098784 3188656
12:45:03 76941 8192000000 10129580240 10596278272 1108358448 11.917 97 11 22 0 1207644480 96797312 2488720
12:47:23 65042 8192000000 10108499888 10550235136 1074039600 11.591 95 31 62 2 1169403840 88668032 6696208
12:49:36 47714 8192000000 10154048992 10588127232 1130797760 12.129 99 32 65 1 1220533760 83006048 6729952
12:51:30 40648 8192000000 10208243344 10644115456 1152727952 12.335 100 34 67 3 1296037472 136206544 7102976
12:53:12 47921 8192000000 10169002272 10676592640 1120654064 12.033 98 27 54 2 1306459552 179843872 5961616
12:55:01 26971 8192000000 10112718704 10571399168 1108719696 11.920 98 34 68 1 1189474832 73457200 7297936
12:56:29 15650 8192000000 10126795776 10556256256 1135302352 12.171 100 41 82 2 1208843424 65991680 7549392
12:57:47 18756 8192000000 10118678864 10552061952 1139533872 12.211 100 35 70 1 1222127632 75786624 6807136
12:59:07 125270 8192000000 10152812032 10620026880 1126579104 12.089 98 38 76 0 1254828976 120109120 8140752
13:02:24 45972 8192000000 10075467872 10519478272 1147182128 12.283 100 29 57 0 1195699584 42816960 5700496
13:04:12 79005 8192000000 10067155136 10558758912 1134191072 12.161 99 5 10 2 1165870464 30594240 1085152
13:06:31 98943 8192000000 10079809920 10543063040 1125876016 12.082 99 16 32 0 1174058800 44844080 3338704
13:09:10 19547 8192000000 10080376688 10525802496 1133524576 12.155 100 38 74 1 1172545472 31776032 7244864
13:10:35 69257 8192000000 10056112608 10504712192 1060517056 11.461 93 16 32 1 1103191648 38604608 4069984
13:12:47 23900 8192000000 10073598064 10510053376 1083039472 11.676 96 30 60 1 1137691600 48375344 6276784
13:14:15 24389 8192000000 10167085424 10617028608 1131979472 12.140 100 46 92 2 1239293280 97927152 9386656
13:15:52 46009 8192000000 10168794352 10602958848 1142359888 12.238 100 36 72 0 1257970704 108107888 7502928
13:18:09 70025 8192000000 10102572896 10603147264 1125819376 12.082 99 18 35 2 1210274368 80678752 3776240
13:20:20 52937 8192000000 10149740608 10615885824 1110760512 11.940 97 21 42 0 1218900768 103551712 4588544
13:22:21 107410 8192000000 10100503504 10597609472 1089466128 11.738 96 24 48 0 1178285344 83947024 4872192
13:25:19 74239 8192000000 10087632320 10560831488 1135054464 12.169 100 11 22 1 1191097040 53708592 2333984
13:27:35 117805 8192000000 10116708320 10639552512 1136562688 12.183 99 12 24 1 1212165424 73013632 2589104
13:30:40 43385 8192000000 10068208928 10541645824 1116136512 11.990 98 23 45 0 1173117664 51432432 5548720
13:32:55 54340 8192000000 10099944864 10558464000 1142693200 12.241 100 15 30 0 1201713056 55497040 3522816
13:34:50 28231 8192000000 10084187136 10524168192 1133546528 12.155 99 42 84 1 1166178768 24241856 8390384
13:36:19 41562 8192000000 10097245552 10525736960 1064936512 11.504 94 36 72 0 1145379264 73044016 7398736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-05-2022 13:39:05 to 26-05-2022 13:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-05-2022 13:39:22 to 26-05-2022 13:39:24.

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          212622
MON$OLDEST_ACTIVE               217238
MON$OLDEST_SNAPSHOT             217238
MON$NEXT_TRANSACTION            217238
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                       2037728
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:56: 
:57: mscs-test-161.budzdorov.ru    Thu May 26 13:37:31 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Thu May 26 13:37:33 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Thu May 26 13:37:33 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
--- end of diff output ---

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

26.05.22 13:39:25. 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
2022-05-26 13:39:28 No severe PSQL-related problems occured
26.05.22 13:39:29. End of report.