Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 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 16295408 kB
MemAvailable 31640352 kB
Buffers 0 kB
Cached 15440100 kB
SwapCached 2180 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-03-30 10:37:15
DTS_END                         2022-03-30 10:37:15.1020
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/44704
TRN_ID                          221668
ATT_ID                          601
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: 9028.85 24939 1083462
customer order: creation 166.36 479 19963
customer order: refuse 14.23 454 1708
order to supplier: creation 44.58 1312 5350
order to supplier: removal 3.63 155783 435
invoice (draft): creation 45.03 3302 5403
invoice (draft): removal 51.38 1022 6166
invoice accept: apply 41.30 26211 4956
invoice accept: cancel 46.19 1289 5543
customer reserve: creation 3820.68 249 458482
customer reserve: removal 4508.77 98 541052
realization accept: apply 81.68 290 9801
realization accept: cancel 76.37 154 9164
payment from customer: creation 59.18 178 7101
payment from customer: removal 6.06 141 727
payment to supplier: creation 47.97 217 5756
payment to supplier: removal 3.43 116 412
service: total inventory turnovers 3.91 4370 469
service: total monetary turnovers 7.28 402 873
service: aggregate perf. data 0.80 19305 96
service: refresh index statistics 0.04 283408 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-03-2022 10:38:54 to 30-03-2022 10:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11001
TEST_TIME 2 9417
TEST_TIME 3 7958
TEST_TIME 4 7355
TEST_TIME 5 7071
TEST_TIME 6 7084
TEST_TIME 7 6974
TEST_TIME 8 6874
TEST_TIME 9 6715
TEST_TIME 10 6904
TEST_TIME 11 7166
TEST_TIME 12 7269
TEST_TIME 13 7355
TEST_TIME 14 7523
TEST_TIME 15 7807
TEST_TIME 16 7926
TEST_TIME 17 8140
TEST_TIME 18 8353
TEST_TIME 19 8470
TEST_TIME 20 8633
TEST_TIME 21 8501
TEST_TIME 22 8367
TEST_TIME 23 8874
TEST_TIME 24 8920
TEST_TIME 25 8827
TEST_TIME 26 8931
TEST_TIME 27 8805
TEST_TIME 28 9056
TEST_TIME 29 9463
TEST_TIME 30 10244
TEST_TIME 31 10458
TEST_TIME 32 10571
TEST_TIME 33 10783
TEST_TIME 34 10917
TEST_TIME 35 10907
TEST_TIME 36 11119
TEST_TIME 37 11006
TEST_TIME 38 10780
TEST_TIME 39 10727
TEST_TIME 40 10631
TEST_TIME 41 10506
TEST_TIME 42 10378
TEST_TIME 43 10317
TEST_TIME 44 10307
TEST_TIME 45 10391
TEST_TIME 46 10338
TEST_TIME 47 10411
TEST_TIME 48 10480
TEST_TIME 49 10409
TEST_TIME 50 10416
TEST_TIME 51 10326
TEST_TIME 52 10205
TEST_TIME 53 10084
TEST_TIME 54 10065
TEST_TIME 55 10030
TEST_TIME 56 9954
TEST_TIME 57 9899
TEST_TIME 58 9891
TEST_TIME 59 9820
TEST_TIME 60 9775
TEST_TIME 61 9765
TEST_TIME 62 9823
TEST_TIME 63 9740
TEST_TIME 64 9655
TEST_TIME 65 9547
TEST_TIME 66 9575
TEST_TIME 67 9550
TEST_TIME 68 9720
TEST_TIME 69 9714
TEST_TIME 70 9754
TEST_TIME 71 9710
TEST_TIME 72 9697
TEST_TIME 73 9739
TEST_TIME 74 9729
TEST_TIME 75 9745
TEST_TIME 76 9788
TEST_TIME 77 9797
TEST_TIME 78 9749
TEST_TIME 79 9774
TEST_TIME 80 9718
TEST_TIME 81 9794
TEST_TIME 82 9800
TEST_TIME 83 9699
TEST_TIME 84 9651
TEST_TIME 85 9621
TEST_TIME 86 9617
TEST_TIME 87 9594
TEST_TIME 88 9539
TEST_TIME 89 9476
TEST_TIME 90 9417
TEST_TIME 91 9391
TEST_TIME 92 9324
TEST_TIME 93 9284
TEST_TIME 94 9365
TEST_TIME 95 9346
TEST_TIME 96 9347
TEST_TIME 97 9315
TEST_TIME 98 9348
TEST_TIME 99 9311
TEST_TIME 100 9356
TEST_TIME 101 9353
TEST_TIME 102 9357
TEST_TIME 103 9355
TEST_TIME 104 9415
TEST_TIME 105 9388
TEST_TIME 106 9397
TEST_TIME 107 9400
TEST_TIME 108 9338
TEST_TIME 109 9316
TEST_TIME 110 9333
TEST_TIME 111 9286
TEST_TIME 112 9254
TEST_TIME 113 9215
TEST_TIME 114 9182
TEST_TIME 115 9171
TEST_TIME 116 9123
TEST_TIME 117 9150
TEST_TIME 118 9123
TEST_TIME 119 9132
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 10:38:58 to 30-03-2022 10:39:01.

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 1505931 1505931 0 0.00 0 18643 13 0 0
doc_list_biud 1505927 1505927 0 0.00 0 37648 41 0 0
sp_add_invoice_to_stock 5113 4956 157 3.07 63 187831 26211 0 157
sp_cancel_adding_invoice 5543 5543 0 0.00 6 84195 1289 0 0
sp_cancel_client_order 1708 1708 0 0.00 6 29873 454 0 0
sp_cancel_customer_reserve 541052 541052 0 0.00 1 28126 98 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 8320 141 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 2855 117 0 0
sp_cancel_supplier_invoice 6178 6166 12 0.19 2 37656 1023 0 12
sp_cancel_supplier_order 435 435 0 0.00 6 1439907 155783 0 0
sp_cancel_write_off 9164 9164 0 0.00 2 17741 155 0 0
sp_client_order 19963 19963 0 0.00 6 40909 479 0 0
sp_customer_reserve 458482 458482 0 0.00 1 52607 250 0 0
sp_fill_shopping_cart 30705 30705 0 0.00 2 11048 78 0 0
sp_fill_shopping_cart_clo_res 458447 458447 0 0.00 0 13883 19 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 30292 281 0 0
sp_kill_cost_storno 15846 15846 0 0.00 0 10272 77 0 0
sp_kill_qstorno_ret_qs2qd 1097869 1097869 0 0.00 0 37647 43 0 0
sp_kill_qty_storno 578771 578771 0 0.00 0 37648 100 0 0
sp_lock_selected_doc 20418 20418 0 0.00 0 10179 8 0 0
sp_make_cost_storno 27562 27562 0 0.00 0 18626 83 0 0
sp_multiply_rows_for_pdistr 27562 27562 0 0.00 0 10119 37 0 0
sp_multiply_rows_for_qdistr 19963 19963 0 0.00 0 10399 155 0 0
sp_pay_from_customer 7101 7101 0 0.00 5 28266 179 0 0
sp_pay_to_supplier 5756 5756 0 0.00 7 35808 217 0 0
sp_payment_common 12855 12855 0 0.00 2 15715 46 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 10097 340 0 0
sp_qd_handle_on_invoice_upd_sts 10445 10445 0 0.00 0 29159 544 0 0
sp_qd_handle_on_reserve_upd_sts 9801 9801 0 0.00 0 16806 76 0 0
sp_reserve_write_off 9801 9801 0 0.00 9 35368 290 0 0
sp_supplier_invoice 5403 5403 0 0.00 12 85867 3302 0 0
sp_supplier_order 5350 5350 0 0.00 8 22631 1312 0 0
srv_aggregate_perf_data 96 96 0 0.00 2278 250576 19306 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 854 157920 35469 0 0
srv_find_qd_qs_mism 1035366 1035366 0 0.00 0 17706 9 0 0
srv_make_invnt_saldo 469 469 0 0.00 72 152088 4370 0 0
srv_make_money_saldo 873 873 0 0.00 1 10304 402 0 0
srv_recalc_idx_stat 5 5 0 0.00 178026 455293 283409 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 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 4962 4962 0 0.00 0 34910 911 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 8153 464 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 4331 26 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 682 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 3856 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 6842 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 3277 49 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 105785 6352 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 6813 24 0 0
t$perf-norm:sp_client_order 19964 19964 0 0.00 0 5923 35 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 7369 23 0 0
t$perf-norm:sp_pay_from_customer 7101 7101 0 0.00 0 9082 20 0 0
t$perf-norm:sp_pay_to_supplier 5756 5756 0 0.00 0 6817 18 0 0
t$perf-norm:sp_reserve_write_off 9801 9801 0 0.00 0 6966 21 0 0
t$perf-norm:sp_supplier_invoice 5403 5403 0 0.00 0 5168 31 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 7607 33 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 245 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 462 13 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 593 14 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 1222 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 209 64 0 0
v_add_invoice_to_stock 10118 9804 314 3.10 0 20493 29 0 314
v_all_customers 36738 36738 0 0.00 0 132 3 0 0
v_all_suppliers 30102 30102 0 0.00 0 170 4 0 0
v_all_wares 39924 39924 0 0.00 0 176 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 3694 20 0 0
v_cancel_client_order 3416 3416 0 0.00 0 29550 30 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 5529 19 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 2041 12 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1505 13 0 0
v_cancel_supplier_invoice 816 792 24 2.94 0 3549 31 0 24
v_cancel_supplier_order 870 870 0 0.00 0 3817 19 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2837 25 0 0
v_max_id_clo_ord 5349 5349 0 0.00 0 614 5 0 0
v_max_id_clo_res 956 956 0 0.00 0 1417 12 0 0
v_max_id_ord_sup 5394 5394 0 0.00 0 7830 6 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 517 7 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 7604 9 0 0
v_min_id_clo_ord 5349 5349 0 0.00 0 1945 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 5563 15 0 0
v_min_id_ord_sup 5394 5394 0 0.00 0 3273 10 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 4761 15 0 0
v_min_non_paid_realizn 7100 7100 0 0.00 0 6643 18 0 0
v_our_firm 10112 10112 0 0.00 0 170 4 0 0
v_reserve_write_off 19598 19598 0 0.00 0 35017 16 0 0
x_lock_depdocs_on_canc_invoice 11313 11313 0 0.00 0 18901 137 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 8653 331 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 2 21966 1152 0 0
x_make_qstorno_1200_2000 5403 5403 0 0.00 5 85025 3091 0 0
x_make_qstorno_2100_3300 458482 458482 0 0.00 0 44394 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2022 10:39:02 to 30-03-2022 10:39:06.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 29570 8192000000 9721217872 10021015552 804161440 8.938 100 90 180 0 833714768 7532432 22020896
08:09:52 21648 8192000000 9761122016 10067480576 835018048 9.250 100 86 172 0 867594080 12246960 20329072
08:11:17 18861 8192000000 9804729536 10121904128 864553056 9.546 100 83 166 0 910442352 26763328 19125968
08:12:38 8867 8192000000 9866622096 10199719936 922956272 10.125 100 81 162 1 975670032 35242576 17471184
08:13:49 5621 8192000000 9924049520 10269675520 966583520 10.553 100 79 158 0 1037659312 55282608 15793184
08:14:57 2820 8192000000 9978022912 10331734016 988607312 10.768 100 89 170 0 1092932144 86892672 17432160
08:16:02 1714 8192000000 9984055296 10346061824 1014527696 11.019 100 88 172 2 1102236128 69599872 18108560
08:17:06 1069 8192000000 10004291792 10373791744 1039489728 11.260 100 93 182 0 1126350800 68355552 18505520
08:18:09 870 8192000000 10034065248 10406756352 1056571424 11.424 100 98 195 1 1160682992 84532384 19579184
08:19:11 1235 8192000000 10047440192 10426220544 1070138608 11.553 100 95 187 0 1171338496 82502064 18697824
08:20:13 967 8192000000 10052712112 10434588672 1072440656 11.575 100 88 175 0 1174546832 83976528 18129648
08:21:15 1099 8192000000 10050992128 10431336448 1074520496 11.595 100 93 178 0 1172437232 78466480 19450256
08:22:17 1960 8192000000 10018925696 10403627008 1080897840 11.656 100 78 143 1 1139187296 43615472 14673984
08:23:19 689 8192000000 10045453168 10431651840 1085781600 11.703 100 89 166 1 1167369856 64037680 17550576
08:24:20 1125 8192000000 10072738960 10455769088 1088499344 11.728 100 94 186 1 1193819168 86345024 18974800
08:25:23 1064 8192000000 10077158608 10466910208 1095259984 11.793 100 97 191 3 1196570096 82075744 19234368
08:26:25 1242 8192000000 10038440384 10423263232 1095997424 11.800 100 95 182 1 1160959936 45306176 19656336
08:27:27 1509 8192000000 10060141312 10448703488 1091806944 11.760 100 94 181 2 1175907856 66320000 17780912
08:28:29 1332 8192000000 10065340528 10448662528 1091714384 11.759 100 98 185 1 1186788224 75252480 19821360
08:29:31 689 8192000000 10062218880 10454048768 1083332032 11.679 100 83 163 0 1184136384 84928000 15876352
08:30:33 1994 8192000000 10065784128 10452246528 1079461664 11.642 100 92 176 0 1189368448 92227376 17679408
08:31:35 817 8192000000 10005617040 10395746304 1064157296 11.496 100 69 129 1 1131301392 53890320 13253776
08:32:39 1324 8192000000 10002214800 10378424320 1041133216 11.276 99 90 171 0 1124856512 65023952 18699344
08:33:41 13201 8192000000 9980351824 10359099392 1036229568 11.228 100 61 118 2 1097733712 50172896 11331248
08:34:59 3483 8192000000 9988576288 10362810368 1023321968 11.104 99 72 132 3 1108658432 71072144 14264320
08:36:03 1120 8192000000 9969973168 10338115584 1009845248 10.974 100 92 167 1 1095794576 67273104 18676224
08:37:05 2405 8192000000 10003385328 10381557760 1031301024 11.181 100 82 158 0 1124797824 78229040 15267760
08:38:16 7103 8192000000 10006831408 10382626816 1053657216 11.396 100 84 157 0 1126676016 58389568 14629232
08:39:33 1934 8192000000 10038155056 10421628928 1054727680 11.406 99 84 163 1 1162567440 90231680 17608080
08:40:38 7870 8192000000 10064752000 10457264128 1082486976 11.671 100 69 134 0 1178653696 81613392 14553328
08:41:50 11654 8192000000 10081763088 10485714944 1088825088 11.731 100 57 110 1 1202678800 101769712 12084000
08:43:05 19487 8192000000 10133494896 10531590144 1106481440 11.899 100 66 125 0 1250018544 129873040 13664064
08:44:31 1319 8192000000 10159362720 10550943744 1123413968 12.059 100 91 177 1 1278463696 134638304 20411424
08:45:37 1509 8192000000 10198342864 10593611776 1127368624 12.097 100 77 150 0 1315232320 170009024 17854672
08:46:49 1082 8192000000 10240633728 10636845056 1149818064 12.308 100 96 189 1 1358314112 186408976 22087072
08:47:53 1795 8192000000 10280260224 10680356864 1149731936 12.307 100 90 176 1 1390366064 221495968 19138160
08:49:01 1840 8192000000 10289330480 10690965504 1164154240 12.442 100 83 160 0 1399375792 217810528 17411024
08:50:06 841 8192000000 10309199696 10724225024 1164481280 12.445 100 93 178 3 1418585952 234411488 19693184
08:51:10 1245 8192000000 10352203392 10762993664 1169589536 12.493 100 89 173 1 1460732208 271510384 19632288
08:52:13 1398 8192000000 10349645600 10767474688 1168265312 12.481 100 95 188 2 1458362992 268707008 21390672
08:53:17 1783 8192000000 10383461408 10801532928 1178248752 12.574 100 81 159 0 1490873936 293957840 18667344
08:54:22 1420 8192000000 10373754864 10817171456 1177103872 12.563 100 98 189 1 1480104272 280194800 22805600
08:55:25 1171 8192000000 10426963824 10850873344 1193661648 12.717 100 93 184 0 1535309280 320387696 21259936
08:56:29 2135 8192000000 10439808688 10866302976 1193298432 12.714 100 83 164 2 1542337872 330158528 18880912
08:57:40 9630 8192000000 10420525728 10865336320 1188886016 12.673 100 59 116 0 1528690560 325614592 14189952
08:59:01 4323 8192000000 10459337792 10878504960 1179417488 12.585 100 65 122 3 1564905296 370038032 15449776
09:00:09 5736 8192000000 10470740640 10920857600 1166096912 12.460 99 61 122 0 1577254048 395715616 15441520
09:01:17 1188 8192000000 10425307312 10834665472 1184205440 12.629 100 86 162 4 1528165584 323661904 20298240
09:02:20 1682 8192000000 10465894864 10891091968 1181757200 12.607 100 62 117 2 1574380000 379078208 13544592
09:03:25 6839 8192000000 10523258800 10982699008 1185829920 12.645 100 64 127 1 1642437328 440814784 15792624
09:04:32 14657 8192000000 10550377536 10987094016 1159487136 12.398 100 43 86 0 1647650752 476164704 11998912
09:05:58 3723 8192000000 10508847200 11001745408 1162407328 12.426 100 35 70 1 1619367600 447477664 9482608
09:07:03 10139 8192000000 10349265296 10903642112 1147662576 12.288 100 36 70 0 1453412416 296433728 9316112
09:08:25 8027 8192000000 10263386800 10762559488 1142477520 12.239 100 34 68 1 1387737360 236080736 9179104
09:09:35 8403 8192000000 10217126064 10678587392 1117476512 12.003 98 39 78 1 1328366336 201811568 9078256
09:10:48 17772 8192000000 10222598256 10644013056 1140266624 12.218 100 57 110 2 1320234272 167307840 12659808
09:12:15 19876 8192000000 10141301264 10598498304 1122128336 12.047 100 23 46 0 1239624512 112244992 5251184
09:13:42 99501 8192000000 10058918128 10506317824 1107975520 11.913 98 10 20 1 1157525616 47796448 1753648
09:16:22 95407 8192000000 10081101232 10534989824 1133495280 12.154 100 14 29 0 1175445424 38989760 2960384
09:18:59 59625 8192000000 10124318944 10562949120 1131188944 12.133 98 30 59 1 1190594832 53314672 6091216
09:21:21 70496 8192000000 10172354480 10646274048 1137127264 12.188 99 13 26 0 1260801056 120791920 2881872
09:23:41 157920 8192000000 10079397760 10550865920 1058617024 11.443 93 16 32 0 1127192720 64998480 3577216
09:27:20 58411 8192000000 10077257120 10504323072 1130427360 12.125 99 26 48 3 1158187712 22553008 5207344
09:29:21 124756 8192000000 10091345712 10541568000 1147834352 12.289 100 23 46 0 1213729728 61092928 4802448
09:32:27 57766 8192000000 10149564032 10574426112 1145991200 12.272 99 37 71 1 1200678432 47988256 6698976
09:34:28 49848 8192000000 10117756688 10573389824 1151612432 12.325 100 23 46 0 1230403264 73924256 4866576
09:36:22 33771 8192000000 10112134640 10554155008 1129832192 12.120 98 30 60 0 1197183648 59518784 7832672
09:38:01 4948 8192000000 10126472064 10547015680 1122624336 12.052 98 83 157 3 1207956480 67166784 18165360
09:39:09 45882 8192000000 10124812224 10600452096 1118066016 12.009 98 19 38 0 1196850944 74575280 4209648
09:41:12 74110 8192000000 10186212064 10640461824 1147689840 12.288 100 24 48 0 1270977536 117996496 5291200
09:44:40 54698 8192000000 10138077152 10588729344 1102048176 11.857 97 19 36 1 1239893120 133575376 4269568
09:46:35 66229 8192000000 10104280688 10545278976 1124299696 12.068 99 14 28 1 1185107616 58320208 2487712
09:48:46 31645 8192000000 10110977808 10548817920 1118660304 12.014 98 27 54 2 1168669616 43658672 6350640
09:50:47 44238 8192000000 10140941008 10588483584 1123924064 12.064 99 27 54 0 1217786416 87737936 6124416
09:52:32 29371 8192000000 10088003632 10544852992 1114356768 11.974 99 26 52 1 1171555424 51796592 5402064
09:54:07 56701 8192000000 10069863632 10499416064 1110431424 11.937 98 26 52 0 1156446640 40658160 5357056
09:56:05 17408 8192000000 10104211312 10547212288 1121287792 12.039 99 44 86 0 1180669776 50838832 8543152
09:57:22 9811 8192000000 10110859472 10535366656 1132056672 12.141 99 44 88 1 1195479616 54713648 8709296
09:58:32 48034 8192000000 10111531360 10604998656 1124895584 12.073 99 28 56 0 1216977584 86407232 5674768
10:00:52 113581 8192000000 10102376304 10584006656 1129900480 12.120 99 36 72 0 1198033200 60644000 7488720
10:03:47 43914 8192000000 10080826192 10545020928 1128685504 12.109 99 16 28 2 1197415936 62922784 5807648
10:05:37 64198 8192000000 10057434320 10501132288 1089575232 11.739 95 23 46 0 1127095472 32440272 5079968
10:07:53 68505 8192000000 10106090352 10566811648 1111546000 11.947 97 12 24 2 1178328848 63884896 2897952
10:10:03 40306 8192000000 10135462800 10579890176 1107364400 11.907 97 26 50 1 1212594864 98860352 6370112
10:11:44 50080 8192000000 10080670048 10551738368 1120640272 12.033 99 13 26 0 1169303568 45762608 2900688
10:13:39 54410 8192000000 10092671840 10530332672 1089048736 11.734 96 31 62 0 1154515200 58703024 6763440
10:15:34 24453 8192000000 10071755712 10504626176 1118211264 12.010 99 24 46 0 1145012544 22116064 4685216
10:17:03 49305 8192000000 10096450096 10545356800 1117003456 11.999 99 41 82 0 1218508688 93428560 8076672
10:19:05 27827 8192000000 10095738608 10544664576 1114285824 11.973 98 43 87 2 1168544592 45824416 8434352
10:20:36 63531 8192000000 10072899184 10562863104 1110902080 11.941 98 27 54 0 1220708704 104407488 5399136
10:22:44 8619 8192000000 10113717696 10534559744 1140850304 12.224 100 55 108 0 1197148384 45612976 10685104
10:23:55 10895 8192000000 10087674624 10506219520 1131904240 12.139 100 55 100 1 1171772704 30059696 9808768
10:25:08 128190 8192000000 10090800048 10567061504 1136879216 12.186 100 34 68 1 1216230416 72641584 6709616
10:28:17 30668 8192000000 10091325552 10556661760 1123393392 12.059 99 19 37 0 1170588304 43169744 4025168
10:29:53 66965 8192000000 10086571152 10567143424 1134489040 12.164 100 18 36 0 1198555616 59942032 4124544
10:32:00 78015 8192000000 10132407808 10588921856 1124334992 12.068 98 19 38 2 1208483792 79244400 4904400
10:34:57 90953 8192000000 10144264912 10597634048 1100689344 11.844 96 18 36 0 1203115616 98805152 3621120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-03-2022 10:39:08 to 30-03-2022 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 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 30-03-2022 10:39:25 to 30-03-2022 10:39:27.

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          218905
MON$OLDEST_ACTIVE               221813
MON$OLDEST_SNAPSHOT             221813
MON$NEXT_TRANSACTION            221813
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                       2086112
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:211: 
:212: mscs-test-161.budzdorov.ru    Wed Mar 30 10:37:17 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Wed Mar 30 10:37:17 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Wed Mar 30 10:37:23 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Wed Mar 30 10:37:25 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

30.03.22 10:39:28. 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-03-30 10:39:31 No severe PSQL-related problems occured
30.03.22 10:39:32. End of report.