Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.07.2022 04: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 15438128 kB
MemAvailable 30995900 kB
Buffers 20 kB
Cached 16232024 kB
SwapCached 2512 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-07-31 04:37:20
DTS_END                         2022-07-31 04:37:20.5960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42578
TRN_ID                          221694
ATT_ID                          559
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: 9365.25 25011 1123830
customer order: creation 168.48 496 20217
customer order: refuse 13.08 387 1569
order to supplier: creation 43.82 1368 5258
order to supplier: removal 3.53 166823 424
invoice (draft): creation 44.43 2965 5332
invoice (draft): removal 53.70 879 6444
invoice accept: apply 40.53 26033 4864
invoice accept: cancel 48.51 1103 5821
customer reserve: creation 3816.72 243 458006
customer reserve: removal 4839.50 96 580740
realization accept: apply 82.65 294 9918
realization accept: cancel 83.44 168 10013
payment from customer: creation 58.37 189 7004
payment from customer: removal 5.04 130 605
payment to supplier: creation 47.03 207 5643
payment to supplier: removal 3.32 124 398
service: total inventory turnovers 4.13 3984 496
service: total monetary turnovers 8.01 366 961
service: aggregate perf. data 0.94 15352 113
service: refresh index statistics 0.03 279020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 04:39:08 to 31-07-2022 04:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12504
TEST_TIME 2 8582
TEST_TIME 3 7162
TEST_TIME 4 6451
TEST_TIME 5 6614
TEST_TIME 6 6684
TEST_TIME 7 6551
TEST_TIME 8 6194
TEST_TIME 9 6670
TEST_TIME 10 7368
TEST_TIME 11 7445
TEST_TIME 12 7569
TEST_TIME 13 7453
TEST_TIME 14 7602
TEST_TIME 15 7524
TEST_TIME 16 7574
TEST_TIME 17 7481
TEST_TIME 18 7380
TEST_TIME 19 7264
TEST_TIME 20 7654
TEST_TIME 21 7853
TEST_TIME 22 7887
TEST_TIME 23 7999
TEST_TIME 24 8092
TEST_TIME 25 8399
TEST_TIME 26 8773
TEST_TIME 27 9097
TEST_TIME 28 9100
TEST_TIME 29 9233
TEST_TIME 30 9682
TEST_TIME 31 9690
TEST_TIME 32 9799
TEST_TIME 33 10509
TEST_TIME 34 10798
TEST_TIME 35 11002
TEST_TIME 36 10951
TEST_TIME 37 10877
TEST_TIME 38 10945
TEST_TIME 39 11084
TEST_TIME 40 10968
TEST_TIME 41 10801
TEST_TIME 42 10678
TEST_TIME 43 10488
TEST_TIME 44 10508
TEST_TIME 45 10530
TEST_TIME 46 10484
TEST_TIME 47 10338
TEST_TIME 48 10199
TEST_TIME 49 10120
TEST_TIME 50 10124
TEST_TIME 51 10198
TEST_TIME 52 10170
TEST_TIME 53 10032
TEST_TIME 54 10167
TEST_TIME 55 10122
TEST_TIME 56 9982
TEST_TIME 57 10130
TEST_TIME 58 10056
TEST_TIME 59 10045
TEST_TIME 60 10023
TEST_TIME 61 10054
TEST_TIME 62 10059
TEST_TIME 63 10179
TEST_TIME 64 10175
TEST_TIME 65 10220
TEST_TIME 66 10230
TEST_TIME 67 10258
TEST_TIME 68 10261
TEST_TIME 69 10231
TEST_TIME 70 10212
TEST_TIME 71 10197
TEST_TIME 72 10114
TEST_TIME 73 10041
TEST_TIME 74 9937
TEST_TIME 75 9925
TEST_TIME 76 9892
TEST_TIME 77 9859
TEST_TIME 78 9804
TEST_TIME 79 9804
TEST_TIME 80 9753
TEST_TIME 81 9662
TEST_TIME 82 9622
TEST_TIME 83 9668
TEST_TIME 84 9621
TEST_TIME 85 9628
TEST_TIME 86 9614
TEST_TIME 87 9635
TEST_TIME 88 9600
TEST_TIME 89 9705
TEST_TIME 90 9691
TEST_TIME 91 9691
TEST_TIME 92 9694
TEST_TIME 93 9745
TEST_TIME 94 9820
TEST_TIME 95 9802
TEST_TIME 96 9822
TEST_TIME 97 9722
TEST_TIME 98 9649
TEST_TIME 99 9656
TEST_TIME 100 9602
TEST_TIME 101 9562
TEST_TIME 102 9502
TEST_TIME 103 9462
TEST_TIME 104 9452
TEST_TIME 105 9418
TEST_TIME 106 9414
TEST_TIME 107 9391
TEST_TIME 108 9433
TEST_TIME 109 9442
TEST_TIME 110 9429
TEST_TIME 111 9428
TEST_TIME 112 9409
TEST_TIME 113 9420
TEST_TIME 114 9410
TEST_TIME 115 9430
TEST_TIME 116 9425
TEST_TIME 117 9348
TEST_TIME 118 9367
TEST_TIME 119 9438
TEST_TIME 120 9383

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-07-2022 04:39:12 to 31-07-2022 04:39:15.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1548012 1548012 0 0.00 0 35624 12 0 0
doc_list_biud 1548002 1548002 0 0.00 0 59920 41 0 0
sp_add_invoice_to_stock 5040 4864 176 3.49 36 221015 26034 0 176
sp_cancel_adding_invoice 5821 5821 0 0.00 5 108067 1103 0 0
sp_cancel_client_order 1569 1569 0 0.00 6 13151 387 0 0
sp_cancel_customer_reserve 580740 580740 0 0.00 1 59926 97 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 4 4118 130 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 2693 124 0 0
sp_cancel_supplier_invoice 6455 6444 11 0.17 2 15625 879 0 11
sp_cancel_supplier_order 424 424 0 0.00 9 1456138 166823 0 0
sp_cancel_write_off 10013 10013 0 0.00 2 12715 169 0 0
sp_client_order 20217 20217 0 0.00 6 57697 496 0 0
sp_customer_reserve 458006 458006 0 0.00 0 109199 244 0 0
sp_fill_shopping_cart 30794 30794 0 0.00 1 13669 81 0 0
sp_fill_shopping_cart_clo_res 457988 457988 0 0.00 0 17621 20 0 0
sp_get_clo_for_invoice 4832 4832 0 0.00 0 18960 275 0 0
sp_kill_cost_storno 16837 16837 0 0.00 0 6445 73 0 0
sp_kill_qstorno_ret_qs2qd 1178361 1178361 0 0.00 0 59909 41 0 0
sp_kill_qty_storno 619757 619757 0 0.00 0 59920 95 0 0
sp_lock_selected_doc 20193 20193 0 0.00 0 8572 8 0 0
sp_make_cost_storno 27396 27396 0 0.00 0 35621 79 0 0
sp_multiply_rows_for_pdistr 27393 27393 0 0.00 0 9721 37 0 0
sp_multiply_rows_for_qdistr 20217 20217 0 0.00 0 57676 195 0 0
sp_pay_from_customer 7004 7004 0 0.00 5 19706 190 0 0
sp_pay_to_supplier 5643 5643 0 0.00 6 35631 208 0 0
sp_payment_common 12645 12645 0 0.00 2 8255 49 0 0
sp_qd_handle_on_cancel_clo 1569 1569 0 0.00 0 13142 308 0 0
sp_qd_handle_on_invoice_upd_sts 10651 10651 0 0.00 0 15435 501 0 0
sp_qd_handle_on_reserve_upd_sts 9916 9916 0 0.00 0 13889 76 0 0
sp_reserve_write_off 9918 9918 0 0.00 7 14022 294 0 0
sp_supplier_invoice 5332 5332 0 0.00 8 92789 2966 0 0
sp_supplier_order 5258 5258 0 0.00 7 29242 1368 0 0
srv_aggregate_perf_data 113 113 0 0.00 2626 189261 15353 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 690 128256 38013 0 0
srv_find_qd_qs_mism 1077067 1077067 0 0.00 0 13399 9 0 0
srv_make_invnt_saldo 496 496 0 0.00 35 133638 3985 0 0
srv_make_money_saldo 961 961 0 0.00 1 23847 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 53332 562585 279021 0 0
t$perf-abend:sp_add_invoice_to_stock 176 176 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 1 12036 994 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 6967 410 0 0
t$perf-norm:sp_cancel_client_order 1569 1569 0 0.00 0 2319 28 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 915 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 1362 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 816 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 2116 45 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 114390 7035 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1516 19 0 0
t$perf-norm:sp_client_order 20217 20217 0 0.00 0 4137 37 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 623 15 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 6545 20 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 3506 22 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 5864 23 0 0
t$perf-norm:sp_supplier_invoice 5332 5332 0 0.00 0 9227 38 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 5646 35 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 467 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 132 10 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 1986 18 0 0
t$perf-norm:srv_make_money_saldo 961 961 0 0.00 0 3849 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 233 65 0 0
v_add_invoice_to_stock 10006 9654 352 3.51 0 4804 28 0 352
v_all_customers 37450 37450 0 0.00 0 68 4 0 0
v_all_suppliers 29766 29766 0 0.00 0 74 5 0 0
v_all_wares 40429 40429 0 0.00 0 258 4 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 3449 20 0 0
v_cancel_client_order 3138 3138 0 0.00 0 4578 14 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 2812 16 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 2430 16 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1325 13 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 2786 30 0 22
v_cancel_supplier_order 848 848 0 0.00 0 1305 13 0 0
v_cancel_write_off 1962 1962 0 0.00 0 4213 31 0 0
v_max_id_clo_ord 5256 5256 0 0.00 0 2670 6 0 0
v_max_id_clo_res 941 941 0 0.00 0 1424 12 0 0
v_max_id_ord_sup 5319 5319 0 0.00 0 3711 7 0 0
v_max_non_paid_invoice 1334 1334 0 0.00 0 1006 10 0 0
v_max_non_paid_realizn 3436 3436 0 0.00 0 320 8 0 0
v_min_id_clo_ord 5256 5256 0 0.00 0 8111 12 0 0
v_min_id_clo_res 941 941 0 0.00 0 6302 24 0 0
v_min_id_ord_sup 5319 5319 0 0.00 0 3158 8 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 8249 19 0 0
v_min_non_paid_realizn 7002 7002 0 0.00 0 5761 21 0 0
v_our_firm 10108 10108 0 0.00 0 65 5 0 0
v_reserve_write_off 19829 19829 0 0.00 0 3479 12 0 0
x_lock_depdocs_on_canc_invoice 11865 11865 0 0.00 0 35708 125 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 4720 326 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 3 29200 1184 0 0
x_make_qstorno_1200_2000 5332 5332 0 0.00 3 92677 2762 0 0
x_make_qstorno_2100_3300 458003 458003 0 0.00 0 109173 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 04:39:16 to 31-07-2022 04:39:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:24 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:25 26359 8192000000 9718205344 10019172352 800681968 8.903 100 77 154 0 826950000 7591216 18676816
02:09:54 23791 8192000000 9776221536 10083848192 840993776 9.310 100 91 182 0 875954208 13224832 21735600
02:11:24 19498 8192000000 9813410688 10133336064 876658560 9.666 100 86 172 0 919831520 22858432 20314528
02:12:48 7379 8192000000 9866660288 10200363008 926509648 10.160 100 83 166 0 982338864 37848176 17981040
02:14:00 3625 8192000000 9942662144 10287157248 972614400 10.612 100 84 168 0 1060783376 70477312 17691664
02:15:07 3007 8192000000 9967536480 10326892544 1000675888 10.885 100 82 155 1 1082825216 66437088 15712240
02:16:12 1452 8192000000 10001256960 10360741888 1019584384 11.068 100 96 181 0 1116800144 78697376 18518384
02:17:16 1281 8192000000 10009036704 10380079104 1048946896 11.351 100 96 178 2 1131988144 64565904 18475344
02:18:19 829 8192000000 10041682576 10414837760 1065179856 11.506 100 93 177 0 1162605328 79443024 17982448
02:19:20 1029 8192000000 10055487408 10430316544 1069111424 11.544 100 94 183 0 1172973120 85179376 18682320
02:20:22 1064 8192000000 10053369904 10436575232 1081163920 11.659 100 94 187 2 1177919248 77828064 18927264
02:21:25 1033 8192000000 10040475648 10423562240 1079335872 11.641 100 95 173 1 1166574112 68233040 19005200
02:22:27 1173 8192000000 10046559232 10432376832 1084778080 11.693 100 96 185 2 1166485008 62720768 18986160
02:23:30 1044 8192000000 10045227760 10434588672 1090667136 11.749 100 93 184 2 1168184560 58506208 19011216
02:24:32 2402 8192000000 10060475872 10453590016 1094268288 11.783 100 76 144 2 1191310320 82569456 14472576
02:25:36 920 8192000000 10070323968 10462720000 1095046464 11.791 100 94 184 0 1195798576 81665184 19086928
02:26:38 1027 8192000000 10050874032 10440441856 1098000464 11.819 100 93 185 0 1171975872 54563216 19412192
02:27:41 1082 8192000000 10072428576 10462609408 1096489776 11.804 100 96 188 0 1198032256 81365968 20176512
02:28:42 1199 8192000000 10057556880 10443669504 1091199232 11.754 100 93 182 0 1179183168 68860144 19123792
02:29:45 1509 8192000000 10019144192 10399256576 1068895936 11.542 99 86 162 3 1141207408 56334624 15976848
02:30:47 1366 8192000000 10016048160 10397175808 1066792048 11.521 100 87 159 1 1136938656 55850240 14296368
02:31:49 1016 8192000000 10017539552 10397708288 1062485376 11.480 100 93 178 1 1139636912 58395648 18755888
02:32:51 1026 8192000000 9980699744 10346582016 1014536256 11.019 99 89 169 0 1100274000 67672256 18065488
02:33:52 1127 8192000000 9997629040 10371833856 1044394640 11.307 100 86 164 1 1117926864 56941808 16590416
02:34:54 1116 8192000000 9992795392 10374152192 1040959120 11.274 100 73 128 4 1112382464 57103328 14320016
02:35:57 953 8192000000 9975027360 10346840064 1037375120 11.239 100 92 170 1 1095459056 40398096 17685840
02:36:58 6560 8192000000 9986329872 10368753664 1058470688 11.442 100 68 112 2 1111770224 40367552 12931984
02:38:09 1123 8192000000 10054043696 10438631424 1075580064 11.605 100 86 164 1 1178001936 87340864 15081008
02:39:18 8504 8192000000 10027346448 10415996928 1099441616 11.832 100 74 143 2 1153139264 37666544 16031104
02:40:32 9223 8192000000 10063055712 10469060608 1108503408 11.918 100 58 112 1 1181657424 61175440 11978576
02:42:00 5590 8192000000 10111618480 10506584064 1123506176 12.060 100 72 134 2 1228536960 91814944 13215840
02:43:09 15956 8192000000 10143163344 10543411200 1135726528 12.175 100 77 149 2 1260105296 108264128 16114640
02:44:29 1544 8192000000 10190801744 10591453184 1149583376 12.306 100 91 177 3 1306884384 138553280 18747728
02:45:36 1314 8192000000 10231713344 10630811648 1158622192 12.390 100 73 141 0 1349350368 176150032 14578144
02:46:39 1303 8192000000 10266698272 10680467456 1173817248 12.532 100 96 179 1 1378375328 184133520 20424560
02:47:42 1182 8192000000 10268069696 10681794560 1182947136 12.618 100 81 154 0 1385523040 184360336 18215568
02:48:47 2252 8192000000 10297425376 10705928192 1186577008 12.651 100 84 157 1 1412663872 209237424 16849440
02:49:53 1401 8192000000 10289664464 10700013568 1179233904 12.583 100 75 141 2 1403722896 208623376 15865616
02:51:00 1660 8192000000 10315453696 10731925504 1189322912 12.677 100 91 179 5 1421600416 211967424 20310080
02:52:05 4241 8192000000 10336311392 10743562240 1193765200 12.718 100 79 156 0 1450213168 236397104 20050864
02:53:13 2326 8192000000 10392938064 10803814400 1210422384 12.873 100 89 168 0 1500888416 270669472 19796560
02:54:19 1232 8192000000 10423559088 10835517440 1210826160 12.877 100 91 172 0 1535081360 304222336 20032864
02:55:25 911 8192000000 10437186384 10844606464 1196701568 12.746 100 97 188 2 1550354880 329693600 23959712
02:56:29 865 8192000000 10465152000 10901766144 1190404064 12.687 100 89 177 0 1572280208 360245504 21630640
02:57:32 991 8192000000 10495792464 10917036032 1181678880 12.606 100 98 194 1 1602619584 397640416 23300288
02:58:36 1130 8192000000 10512665648 10930200576 1189012416 12.674 100 96 187 2 1617522592 404573904 23936272
02:59:40 1161 8192000000 10549238848 10980298752 1190662240 12.690 99 89 175 2 1651647680 440411040 20574400
03:00:44 833 8192000000 10586213280 11036794880 1200992144 12.786 100 97 193 0 1692126048 467778832 23355072
03:01:49 1126 8192000000 10506777904 10988146688 1186212032 12.648 100 95 181 0 1617064976 408258144 22594800
03:02:53 681 8192000000 10514248640 10997968896 1185356384 12.640 100 99 193 0 1616984608 406849648 24778576
03:03:55 14710 8192000000 10463743488 10953207808 1166790320 12.467 99 52 104 1 1581583584 401249584 13543680
03:05:12 2829 8192000000 10476937760 10898378752 1164398336 12.444 100 58 116 1 1588449920 409542208 14509376
03:06:17 28115 8192000000 10472975888 10943168512 1153474432 12.342 100 36 72 0 1578600784 415352416 9773936
03:07:46 29500 8192000000 10386504128 10830401536 1135102800 12.169 98 35 71 0 1492992288 349244096 8645392
03:09:16 18861 8192000000 10334620656 10850672640 1118508544 12.013 99 25 50 0 1447984256 323008528 6467184
03:10:36 52324 8192000000 10178756368 10653896704 1117367312 12.002 99 15 30 0 1305428304 184473472 3587520
03:12:29 81099 8192000000 10061870864 10510110720 1088209008 11.726 96 12 22 0 1126227888 35872032 2146848
03:14:50 82940 8192000000 10104116928 10610798592 1123604368 12.061 99 34 63 1 1204419744 74284976 6530400
03:17:14 65218 8192000000 10056262096 10480889856 1119558352 12.023 98 31 62 1 1144798128 18996480 6243296
03:19:26 119783 8192000000 10130495456 10578108416 1111653568 11.948 97 36 63 1 1167907920 49925328 6329024
03:22:28 99175 8192000000 10061483744 10503639040 1061296016 11.469 93 23 44 0 1098765872 32759056 4710800
03:25:09 39946 8192000000 10122338112 10541449216 1126314048 12.087 99 57 100 1 1199283920 62739872 10230000
03:26:50 5692 8192000000 10220548000 10650607616 1152675808 12.335 100 58 111 0 1317235920 152660912 11899200
03:27:57 26958 8192000000 10231320032 10697228288 1155725728 12.363 100 35 70 1 1346827632 182856224 8245680
03:29:27 71600 8192000000 10187293408 10642681856 1146937120 12.281 99 18 36 0 1272921712 121759904 4224688
03:31:39 53534 8192000000 10148416464 10652135424 1140092080 12.216 99 11 22 0 1255133808 112697744 2343984
03:33:33 69311 8192000000 10124483088 10605457408 1143943280 12.253 100 16 32 0 1222391216 75133648 3314288
03:36:09 60395 8192000000 10173119936 10619666432 1123583536 12.061 97 28 54 0 1236295984 106425392 6287056
03:39:45 68795 8192000000 10076374272 10536787968 1119240688 12.020 98 33 62 0 1206863536 80547600 7075248
03:41:55 28263 8192000000 10116947136 10574761984 1131759136 12.138 100 35 70 1 1215312208 76239808 7313264
03:43:23 73917 8192000000 10097823472 10583474176 1133212448 12.152 99 16 33 0 1182961568 46456480 3292640
03:45:54 22340 8192000000 10111615888 10540482560 1146167120 12.274 100 34 68 1 1196739040 43383856 7188064
03:47:19 28901 8192000000 10121904272 10560864256 1138957104 12.206 100 29 55 0 1199176816 54641616 5578096
03:48:53 104890 8192000000 10055568272 10522374144 1093565552 11.777 96 27 54 0 1134259104 35095264 5598288
03:51:38 28034 8192000000 10085856960 10524856320 1138217520 12.199 100 38 74 0 1198192720 52723376 7251824
03:53:07 25582 8192000000 10074558336 10519437312 1125339536 12.077 100 32 61 1 1182207088 50734144 6133408
03:54:38 51376 8192000000 10080800992 10529906688 1111632768 11.948 98 28 54 0 1170192496 52991136 5568592
03:56:29 55068 8192000000 10086748064 10544099328 1136798544 12.185 100 14 28 0 1193371920 53280256 3293120
03:58:30 43683 8192000000 10093367408 10580529152 1139545616 12.211 100 8 16 0 1199739056 58485728 1707712
04:00:14 59577 8192000000 10039745008 10490986496 1051573760 11.376 93 15 28 0 1081662656 26907440 3181456
04:02:15 62494 8192000000 10064517296 10526113792 1074754320 11.597 96 20 40 0 1131764288 52542368 4467600
04:04:34 50527 8192000000 10107138384 10574815232 1093031760 11.771 97 34 68 0 1184570768 84595008 6944000
04:06:25 67756 8192000000 10066337824 10555916288 1119403072 12.021 100 19 36 0 1186963312 63588320 3971920
04:08:34 63237 8192000000 10131352112 10588401664 1103955696 11.875 97 33 66 0 1186048816 75418096 6675024
04:10:38 128255 8192000000 10113970784 10603958272 1069973712 11.552 95 8 14 0 1131727520 60115488 1638320
04:13:48 108333 8192000000 10077416864 10519498752 1108749200 11.921 98 21 42 0 1161207152 48254560 4203392
04:16:41 41298 8192000000 10037298432 10495524864 1098208864 11.821 98 9 15 1 1134207024 34751408 1246752
04:18:27 62172 8192000000 10074744304 10554028032 1120786128 12.034 99 9 18 0 1163233360 40636544 1810688
04:20:29 71554 8192000000 10057348416 10571350016 1134114368 12.160 100 10 18 0 1189089472 53101760 1873344
04:22:41 36386 8192000000 10098221568 10533023744 1137397856 12.191 100 40 80 2 1184787344 39605456 7784032
04:24:26 58563 8192000000 10095822608 10564681728 1095877136 11.799 96 26 52 0 1182792448 81387792 5527520
04:26:40 44976 8192000000 10105153040 10554761216 1133877744 12.158 99 26 52 0 1180406304 41029008 5499552
04:28:26 39408 8192000000 10137031968 10599378944 1145459760 12.267 100 40 79 0 1228482368 74473088 8549520
04:30:12 47811 8192000000 10165132208 10640871424 1142623712 12.240 99 36 68 0 1261358112 110978352 7756048
04:32:00 44457 8192000000 10171356032 10640355328 1127172816 12.095 98 31 62 0 1252984768 118978224 6833728
04:33:46 101860 8192000000 10170201168 10652368896 1125424368 12.078 99 45 87 0 1298478176 163945536 9108272
04:36:32 52556 8192000000 9937911168 10481623040 993214448 10.813 88 6 12 0 1043366784 49330304 822032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-07-2022 04:39:22 to 31-07-2022 04:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 176 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-07-2022 04:39:39 to 31-07-2022 04:39:41.

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          221696
MON$OLDEST_ACTIVE               222003
MON$OLDEST_SNAPSHOT             222003
MON$NEXT_TRANSACTION            222003
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                       2097088
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:337: 
:338: mscs-test-161.budzdorov.ru    Sun Jul 31 04:37:40 2022
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:340: 
--- end of diff output ---

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

31.07.22 04:39:42. 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-07-31 04:39:44 No severe PSQL-related problems occured
31.07.22 04:39:46. End of report.