Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2021 10: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 16550848 kB
MemAvailable 31455304 kB
Buffers 0 kB
Cached 15146824 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-12-17 10:37:06
DTS_END                         2021-12-17 10:37:06.9650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49736
TRN_ID                          234850
ATT_ID                          631
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: 8901.94 23546 1068233
customer order: creation 166.70 389 20004
customer order: refuse 13.92 323 1670
order to supplier: creation 45.03 1350 5404
order to supplier: removal 3.29 118894 395
invoice (draft): creation 46.48 3427 5578
invoice (draft): removal 50.68 981 6082
invoice accept: apply 40.10 18886 4812
invoice accept: cancel 45.62 881 5474
customer reserve: creation 3729.13 184 447496
customer reserve: removal 4466.58 68 535989
realization accept: apply 80.53 195 9663
realization accept: cancel 87.48 119 10498
payment from customer: creation 58.33 110 7000
payment from customer: removal 5.85 83 702
payment to supplier: creation 47.72 132 5726
payment to supplier: removal 3.63 89 436
service: total inventory turnovers 3.90 3172 468
service: total monetary turnovers 6.03 467 723
service: aggregate perf. data 0.90 14270 108
service: refresh index statistics 0.04 306900 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2021 10:38:27 to 17-12-2021 10:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7911
TEST_TIME 2 5494
TEST_TIME 3 5528
TEST_TIME 4 4677
TEST_TIME 5 4834
TEST_TIME 6 5168
TEST_TIME 7 5198
TEST_TIME 8 5029
TEST_TIME 9 5132
TEST_TIME 10 5764
TEST_TIME 11 6006
TEST_TIME 12 6644
TEST_TIME 13 7967
TEST_TIME 14 7940
TEST_TIME 15 8211
TEST_TIME 16 8259
TEST_TIME 17 8395
TEST_TIME 18 8766
TEST_TIME 19 8886
TEST_TIME 20 8729
TEST_TIME 21 8725
TEST_TIME 22 9152
TEST_TIME 23 9346
TEST_TIME 24 9119
TEST_TIME 25 9370
TEST_TIME 26 9883
TEST_TIME 27 10689
TEST_TIME 28 11548
TEST_TIME 29 11258
TEST_TIME 30 11105
TEST_TIME 31 11262
TEST_TIME 32 11135
TEST_TIME 33 11004
TEST_TIME 34 10857
TEST_TIME 35 10690
TEST_TIME 36 10453
TEST_TIME 37 10606
TEST_TIME 38 10355
TEST_TIME 39 10354
TEST_TIME 40 10435
TEST_TIME 41 10296
TEST_TIME 42 10235
TEST_TIME 43 10080
TEST_TIME 44 9946
TEST_TIME 45 9908
TEST_TIME 46 9918
TEST_TIME 47 9830
TEST_TIME 48 9719
TEST_TIME 49 9614
TEST_TIME 50 9540
TEST_TIME 51 9478
TEST_TIME 52 9518
TEST_TIME 53 9582
TEST_TIME 54 9628
TEST_TIME 55 9613
TEST_TIME 56 9574
TEST_TIME 57 9512
TEST_TIME 58 9458
TEST_TIME 59 9453
TEST_TIME 60 9584
TEST_TIME 61 9615
TEST_TIME 62 9614
TEST_TIME 63 9652
TEST_TIME 64 9644
TEST_TIME 65 9522
TEST_TIME 66 9507
TEST_TIME 67 9390
TEST_TIME 68 9362
TEST_TIME 69 9445
TEST_TIME 70 9363
TEST_TIME 71 9293
TEST_TIME 72 9317
TEST_TIME 73 9332
TEST_TIME 74 9363
TEST_TIME 75 9360
TEST_TIME 76 9349
TEST_TIME 77 9299
TEST_TIME 78 9314
TEST_TIME 79 9439
TEST_TIME 80 9444
TEST_TIME 81 9419
TEST_TIME 82 9413
TEST_TIME 83 9387
TEST_TIME 84 9369
TEST_TIME 85 9453
TEST_TIME 86 9457
TEST_TIME 87 9474
TEST_TIME 88 9379
TEST_TIME 89 9352
TEST_TIME 90 9342
TEST_TIME 91 9331
TEST_TIME 92 9273
TEST_TIME 93 9226
TEST_TIME 94 9200
TEST_TIME 95 9125
TEST_TIME 96 9117
TEST_TIME 97 9094
TEST_TIME 98 9086
TEST_TIME 99 9081
TEST_TIME 100 9161
TEST_TIME 101 9137
TEST_TIME 102 9115
TEST_TIME 103 9191
TEST_TIME 104 9147
TEST_TIME 105 9235
TEST_TIME 106 9232
TEST_TIME 107 9232
TEST_TIME 108 9233
TEST_TIME 109 9301
TEST_TIME 110 9292
TEST_TIME 111 9226
TEST_TIME 112 9161
TEST_TIME 113 9138
TEST_TIME 114 9098
TEST_TIME 115 9037
TEST_TIME 116 9000
TEST_TIME 117 8974
TEST_TIME 118 9005
TEST_TIME 119 8976
TEST_TIME 120 8908

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2021 10:38:31 to 17-12-2021 10:38:34.

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 1482285 1482285 0 0.00 0 22229 7 0 0
doc_list_biud 1482283 1482283 0 0.00 0 65226 31 0 0
sp_add_invoice_to_stock 4915 4812 103 2.09 27 218498 18886 0 103
sp_cancel_adding_invoice 5474 5474 0 0.00 3 69501 881 0 0
sp_cancel_client_order 1670 1670 0 0.00 5 11452 323 0 0
sp_cancel_customer_reserve 535989 535989 0 0.00 1 65228 68 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 4297 83 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 6932 89 0 0
sp_cancel_supplier_invoice 6092 6082 10 0.16 2 31126 982 0 10
sp_cancel_supplier_order 395 395 0 0.00 7 1077760 118894 0 0
sp_cancel_write_off 10498 10498 0 0.00 1 15196 119 0 0
sp_client_order 20004 20004 0 0.00 4 51923 389 0 0
sp_customer_reserve 447496 447496 0 0.00 0 60224 184 0 0
sp_fill_shopping_cart 30963 30963 0 0.00 1 28752 52 0 0
sp_fill_shopping_cart_clo_res 447484 447484 0 0.00 0 22600 17 0 0
sp_get_clo_for_invoice 4799 4799 0 0.00 0 57763 428 0 0
sp_kill_cost_storno 17110 17110 0 0.00 0 7696 61 0 0
sp_kill_qstorno_ret_qs2qd 1088953 1088953 0 0.00 0 65224 37 0 0
sp_kill_qty_storno 574569 574569 0 0.00 0 65226 80 0 0
sp_lock_selected_doc 20183 20183 0 0.00 0 11334 7 0 0
sp_make_cost_storno 27188 27188 0 0.00 0 17599 58 0 0
sp_multiply_rows_for_pdistr 27188 27188 0 0.00 0 9457 35 0 0
sp_multiply_rows_for_qdistr 20004 20004 0 0.00 0 51750 200 0 0
sp_pay_from_customer 7000 7000 0 0.00 4 13975 110 0 0
sp_pay_to_supplier 5726 5726 0 0.00 5 11110 133 0 0
sp_payment_common 12725 12725 0 0.00 1 10565 29 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 10708 288 0 0
sp_qd_handle_on_invoice_upd_sts 10273 10273 0 0.00 0 19164 374 0 0
sp_qd_handle_on_reserve_upd_sts 9662 9662 0 0.00 0 17125 64 0 0
sp_reserve_write_off 9663 9663 0 0.00 7 22246 195 0 0
sp_supplier_invoice 5578 5578 0 0.00 7 84301 3427 0 0
sp_supplier_order 5404 5404 0 0.00 5 33027 1351 0 0
srv_aggregate_perf_data 108 108 0 0.00 2746 187738 14270 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1624 146407 49977 0 0
srv_find_qd_qs_mism 1021510 1021510 0 0.00 0 10425 6 0 0
srv_make_invnt_saldo 468 468 0 0.00 48 105136 3173 0 0
srv_make_money_saldo 723 723 0 0.00 1 11334 467 0 0
srv_recalc_idx_stat 5 5 0 0.00 145018 418430 306901 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4813 4813 0 0.00 0 11976 186 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 8941 111 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 6320 15 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2547 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1884 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 1322 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1001 15 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 29292 1838 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2248 7 0 0
t$perf-norm:sp_client_order 20004 20004 0 0.00 0 9272 20 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 2237 10 0 0
t$perf-norm:sp_pay_from_customer 7000 7000 0 0.00 0 8919 11 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 6267 12 0 0
t$perf-norm:sp_reserve_write_off 9663 9663 0 0.00 0 6450 11 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 11041 21 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 5255 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 665 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 110 3 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 2400 17 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 353 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9800 9594 206 2.10 0 5275 16 0 206
v_all_customers 36978 36978 0 0.00 0 121 1 0 0
v_all_suppliers 30332 30332 0 0.00 0 25 1 0 0
v_all_wares 40004 40004 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 3687 10 0 0
v_cancel_client_order 3340 3340 0 0.00 0 2375 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 2413 6 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 3069 8 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 946 6 0 0
v_cancel_supplier_invoice 814 794 20 2.45 0 712 16 0 20
v_cancel_supplier_order 790 790 0 0.00 0 431 6 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2064 13 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 1021 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 1489 10 0 0
v_max_id_ord_sup 5565 5565 0 0.00 0 8636 6 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 118 2 0 0
v_max_non_paid_realizn 3549 3549 0 0.00 0 2626 4 0 0
v_min_id_clo_ord 5396 5396 0 0.00 0 3987 8 0 0
v_min_id_clo_res 998 998 0 0.00 0 3002 9 0 0
v_min_id_ord_sup 5565 5565 0 0.00 0 8276 14 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 8978 22 0 0
v_min_non_paid_realizn 6999 6999 0 0.00 0 9716 20 0 0
v_our_firm 9926 9926 0 0.00 0 18 1 0 0
v_reserve_write_off 19324 19324 0 0.00 0 4908 4 0 0
x_lock_depdocs_on_canc_invoice 11159 11159 0 0.00 0 26745 139 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 20591 449 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 2 33002 1268 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 3 84254 3230 0 0
x_make_qstorno_2100_3300 447496 447496 0 0.00 0 53684 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2021 10:38:35 to 17-12-2021 10:38:39.

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
08:07:10 11 8192000000 8802605584 8815370240 7550208 0.092 3 2 2 0 7653504 67520 35776
08:08:11 21061 8192000000 9706151744 9999294464 785108560 8.745 100 89 178 1 814745888 7613216 22024112
08:09:40 18193 8192000000 9744279424 10050359296 823501344 9.134 100 84 167 1 855402592 10726688 21174560
08:11:06 15907 8192000000 9783867936 10100912128 856253616 9.463 100 80 160 0 892046944 17204736 18588592
08:12:27 14449 8192000000 9848652176 10180534272 914252176 10.039 100 78 156 0 959349712 28200928 16896608
08:13:45 5933 8192000000 9926266736 10271043584 959626544 10.485 100 78 156 0 1033257904 56860656 16770704
08:14:55 4747 8192000000 9965706912 10317135872 989687648 10.778 100 85 170 0 1082014320 75496720 16829952
08:16:01 4051 8192000000 9975492464 10336235520 1018598672 11.058 100 85 169 3 1094534528 59150096 16785760
08:17:07 1736 8192000000 10011916912 10377388032 1044537920 11.308 100 92 182 2 1124529296 60758912 19232464
08:18:10 1269 8192000000 10040684112 10410651648 1059728208 11.454 100 96 191 0 1152579248 73258208 19592832
08:19:12 5205 8192000000 9999876832 10393292800 1064555424 11.500 100 37 74 1 1121501104 48951168 7994512
08:20:18 6971 8192000000 10006592752 10397134848 1074311904 11.593 100 28 56 0 1131225520 51061456 5852160
08:21:27 16602 8192000000 9980242976 10389057536 1075412784 11.604 100 19 38 2 1111730432 32640848 3676800
08:22:44 6539 8192000000 10029772464 10427658240 1084897520 11.694 100 50 100 1 1127337872 31612048 10828304
08:23:52 6939 8192000000 10009856080 10419191808 1091442160 11.756 100 35 70 2 1121609328 22299104 7868064
08:25:00 49636 8192000000 10004546064 10404335616 1095613072 11.796 100 52 107 1 1120742784 14105376 11024336
08:26:53 4553 8192000000 10006787152 10412146688 1080889872 11.656 99 36 71 1 1123667536 35165008 7612656
08:27:59 7591 8192000000 9966166384 10361462784 1031728240 11.185 97 29 58 1 1055404784 17178400 6498144
08:29:07 9383 8192000000 9952614768 10347585536 991069280 10.792 95 23 46 0 1013316512 17123264 5123968
08:30:20 39173 8192000000 9935925040 10339086336 947099696 10.363 92 23 46 1 965453296 13474832 4878768
08:32:01 5195 8192000000 9938892496 10319564800 1027595248 11.145 100 44 87 1 1050104736 12942128 9567360
08:33:11 7693 8192000000 9958682768 10341613568 1045329184 11.316 100 47 95 1 1070359872 13384080 11646608
08:34:20 14713 8192000000 9969882000 10371481600 1058723280 11.444 100 26 52 1 1083142960 17762928 6656752
08:35:38 25223 8192000000 9973836800 10380816384 1063637312 11.491 100 18 36 0 1104048128 36683280 3727536
08:37:04 11015 8192000000 9995508944 10393448448 1077043664 11.619 100 51 101 0 1107416016 19114352 11258000
08:38:20 8864 8192000000 10044481520 10439114752 1095500544 11.795 100 58 114 2 1152248256 44201504 12546208
08:39:34 19481 8192000000 10075429824 10475851776 1115133312 11.981 100 48 91 1 1179866864 55262032 9471520
08:41:10 80042 8192000000 10203297696 10618195968 1127490272 12.098 100 47 94 1 1289139360 151391792 10257296
08:44:29 21975 8192000000 10173634816 10595794944 1138782336 12.204 100 34 68 2 1278222240 132032432 7407472
08:45:58 7586 8192000000 10236088512 10636697600 1153054752 12.338 100 66 128 0 1341285328 174301840 13928736
08:47:09 5326 8192000000 10289852736 10693459968 1164451904 12.445 100 74 144 0 1395569568 215520448 15597216
08:48:16 13970 8192000000 10325640224 10740158464 1157916992 12.384 99 52 97 2 1417536512 248185472 11434048
08:49:33 1896 8192000000 10346969584 10751430656 1148808256 12.298 98 77 150 2 1441522992 276704816 16009920
08:50:39 12216 8192000000 10244863584 10672369664 1156058736 12.366 99 49 96 0 1331294832 164771520 10464576
08:51:58 2030 8192000000 10286453264 10684112896 1160160416 12.405 99 87 171 1 1383609488 204310448 19138624
08:53:05 3119 8192000000 10314016944 10712530944 1170998448 12.506 100 86 171 1 1411866368 222369776 18498144
08:54:15 3204 8192000000 10372168896 10771677184 1172489136 12.520 100 89 176 2 1469029296 276120720 20419440
08:55:23 1623 8192000000 10405331328 10803613696 1186232960 12.648 100 93 184 5 1500410848 292200704 21977184
08:56:27 7753 8192000000 10404057712 10823471104 1171927424 12.515 99 66 130 2 1491776160 304885312 14963424
08:57:50 3811 8192000000 10451546304 10870874112 1172771936 12.523 100 55 109 0 1547873360 361693472 13407952
08:59:01 10866 8192000000 10472744816 10894274560 1166972000 12.469 99 48 96 0 1562842752 383236848 12633904
09:00:25 22057 8192000000 10437972448 10875211776 1170002016 12.497 100 33 66 0 1532191216 353490544 8698656
09:01:49 5885 8192000000 10477939872 10902462464 1144027360 12.253 100 48 96 2 1576274544 419744304 12502880
09:02:58 18734 8192000000 10467398304 10912759808 1129676336 12.118 100 28 56 0 1554389504 416563312 8149856
09:04:17 19028 8192000000 10334562240 10828488704 1112666368 11.958 99 30 60 2 1467678848 346529392 8483088
09:05:42 68752 8192000000 10100861344 10536394752 1109258544 11.925 100 18 36 1 1190922080 77379376 4284160
09:07:59 30922 8192000000 10118392320 10615513088 1112422736 11.955 100 22 44 0 1234323232 116981696 4918800
09:09:31 77911 8192000000 10069304736 10521915392 1104382016 11.879 100 14 28 1 1168755616 61219808 3153792
09:12:00 142104 8192000000 10064287376 10561138688 1097838448 11.817 98 22 44 1 1204980736 101809376 5332912
09:15:41 47409 8192000000 10100302304 10540568576 1123501504 12.060 100 33 66 0 1215910672 85607648 6801520
09:17:44 78407 8192000000 10044434272 10489442304 1129701744 12.119 100 22 44 1 1176612784 42371168 4539872
09:20:15 42531 8192000000 10064327920 10508251136 1137934432 12.196 100 20 39 0 1170020320 28289680 3796208
09:21:59 40176 8192000000 10090878272 10524442624 1135519712 12.173 100 35 70 1 1192500496 49419168 7561616
09:23:50 57306 8192000000 10097891936 10580242432 1151132544 12.320 100 12 24 0 1203588016 50168736 2286736
09:26:39 92315 8192000000 10096107040 10570076160 1150853376 12.318 100 17 34 0 1203843184 49549040 3440768
09:29:40 50837 8192000000 10104074160 10576461824 1143665936 12.250 100 18 36 0 1216615776 69433888 3515952
09:31:31 99890 8192000000 10066912928 10506637312 1118112256 12.009 97 11 22 0 1140908544 20545632 2250656
09:34:11 24208 8192000000 10079329888 10503622656 1117365232 12.002 98 30 60 0 1157288112 33979888 5942992
09:35:40 54686 8192000000 10117175168 10574639104 1120152832 12.028 98 30 60 1 1202514864 76307968 6054064
09:37:41 111278 8192000000 10066726576 10541404160 1062878432 11.484 94 9 18 0 1097836640 33087664 1870544
09:40:32 47230 8192000000 10092645360 10544652288 1107074320 11.905 98 20 40 0 1147495120 36217776 4203024
09:42:24 109678 8192000000 10063065344 10518421504 1085332976 11.698 96 22 44 0 1119833472 29983104 4517392
09:45:19 62237 8192000000 10119739888 10592116736 1119677040 12.024 99 20 40 1 1231719664 107570128 4472496
09:47:36 67687 8192000000 10111071136 10558980096 1112191312 11.953 98 13 26 0 1187927568 72802128 2934128
09:49:47 61709 8192000000 10105384896 10567151616 1113080736 11.962 98 21 42 0 1237604624 119291408 5232480
09:51:50 65958 8192000000 10034069648 10499575808 1063180224 11.487 94 18 35 1 1127055360 60127088 3748048
09:54:11 55769 8192000000 10078589280 10515492864 1037334528 11.239 92 26 52 1 1090614288 48199568 5080192
09:56:09 69074 8192000000 10060851424 10496237568 1080914080 11.656 97 15 30 0 1131326256 47215872 3196304
09:58:32 11978 8192000000 10063450176 10490392576 1118432640 12.012 100 54 108 0 1157461888 27668912 11360336
09:59:44 54279 8192000000 10089453088 10508070912 1110596800 11.938 99 34 68 0 1167673120 50290880 6785440
10:01:43 93552 8192000000 10119794928 10623975424 1117760992 12.006 99 17 35 0 1206414048 85204720 3448336
10:04:31 146407 8192000000 10066441296 10529632256 1108247504 11.916 98 27 54 0 1158628272 44377792 6002976
10:08:01 53541 8192000000 10067315664 10546630656 1128954448 12.112 100 9 18 1 1162863616 31961728 1947440
10:10:00 67419 8192000000 10072783728 10525597696 1113054576 11.961 98 21 40 1 1166224080 49159872 4009632
10:12:17 77090 8192000000 10088356720 10569371648 1134638048 12.165 100 18 36 0 1220074384 81157744 4278592
10:14:45 96232 8192000000 10134000704 10587570176 1131187024 12.133 99 13 26 0 1212791232 78932144 2672064
10:17:21 59741 8192000000 10066688288 10513629184 1091078976 11.753 96 20 38 2 1122203680 27087040 4037664
10:19:23 57020 8192000000 10105884976 10602938368 1118210480 12.010 98 21 42 0 1206125040 83401728 4512832
10:21:20 22572 8192000000 10131492560 10559016960 1141888912 12.233 99 45 90 0 1197139072 46180352 9069808
10:22:43 87606 8192000000 10059901296 10498457600 1063581344 11.491 93 16 32 0 1105299152 38684544 3033264
10:25:12 50207 8192000000 10094577664 10545373184 1101561104 11.852 97 39 78 1 1146176688 37230464 7385120
10:27:03 131626 8192000000 10054425040 10519584768 1080002976 11.648 95 15 30 2 1117525488 34328944 3193568
10:30:47 52753 8192000000 10045632704 10502131712 1121432144 12.041 99 3 6 0 1147913168 25995712 485312
10:32:46 96779 8192000000 10070123296 10542718976 1092436272 11.766 96 10 20 0 1153963168 59562896 1964000
10:35:29 51124 8192000000 10036125648 10479079424 1086462720 11.709 97 20 40 0 1122505360 31769248 4273392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2021 10:38:41 to 17-12-2021 10:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-12-2021 10:38:57 to 17-12-2021 10:38:58.

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          232187
MON$OLDEST_ACTIVE               235092
MON$OLDEST_SNAPSHOT             235092
MON$NEXT_TRANSACTION            235092
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                       2047840
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2691 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2691 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2691 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2691 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 ---
:35: 
:36: mscs-test-161.budzdorov.ru    Fri Dec 17 10:37:08 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Fri Dec 17 10:37:09 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Fri Dec 17 10:37:12 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Fri Dec 17 10:37:12 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

17.12.21 10:39:00. 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-12-17 10:39:02 No severe PSQL-related problems occured
17.12.21 10:39:03. End of report.