Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2023 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 14947636 kB
MemAvailable 30450528 kB
Buffers 0 kB
Cached 16762100 kB
SwapCached 3564 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.3
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 2023-02-08 10:37:24
DTS_END                         2023-02-08 10:37:24.7590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42736
TRN_ID                          206983
ATT_ID                          598
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: 9215.98 23975 1105918
customer order: creation 169.96 489 20395
customer order: refuse 14.86 392 1783
order to supplier: creation 44.57 1413 5348
order to supplier: removal 3.53 182258 423
invoice (draft): creation 44.36 2966 5323
invoice (draft): removal 53.13 1007 6375
invoice accept: apply 40.91 25674 4909
invoice accept: cancel 48.13 1169 5776
customer reserve: creation 3793.65 246 455238
customer reserve: removal 4710.42 108 565250
realization accept: apply 82.91 282 9949
realization accept: cancel 81.04 182 9725
payment from customer: creation 58.08 172 6969
payment from customer: removal 5.85 148 702
payment to supplier: creation 48.45 196 5814
payment to supplier: removal 3.63 151 436
service: total inventory turnovers 3.99 4059 479
service: total monetary turnovers 7.66 328 919
service: aggregate perf. data 0.84 17223 101
service: refresh index statistics 0.03 241027 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-02-2023 10:38:57 to 08-02-2023 10:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6751
TEST_TIME 2 4959
TEST_TIME 3 5229
TEST_TIME 4 5070
TEST_TIME 5 5696
TEST_TIME 6 5932
TEST_TIME 7 5986
TEST_TIME 8 6057
TEST_TIME 9 6325
TEST_TIME 10 6248
TEST_TIME 11 6105
TEST_TIME 12 6335
TEST_TIME 13 6560
TEST_TIME 14 6735
TEST_TIME 15 6826
TEST_TIME 16 6877
TEST_TIME 17 7029
TEST_TIME 18 7075
TEST_TIME 19 7375
TEST_TIME 20 7456
TEST_TIME 21 7470
TEST_TIME 22 7507
TEST_TIME 23 8057
TEST_TIME 24 8150
TEST_TIME 25 8348
TEST_TIME 26 8643
TEST_TIME 27 8638
TEST_TIME 28 8848
TEST_TIME 29 8845
TEST_TIME 30 9403
TEST_TIME 31 9437
TEST_TIME 32 10197
TEST_TIME 33 10658
TEST_TIME 34 11286
TEST_TIME 35 11182
TEST_TIME 36 11109
TEST_TIME 37 11091
TEST_TIME 38 11112
TEST_TIME 39 10953
TEST_TIME 40 10939
TEST_TIME 41 10956
TEST_TIME 42 10843
TEST_TIME 43 10646
TEST_TIME 44 10464
TEST_TIME 45 10436
TEST_TIME 46 10440
TEST_TIME 47 10262
TEST_TIME 48 10241
TEST_TIME 49 10227
TEST_TIME 50 10205
TEST_TIME 51 10262
TEST_TIME 52 10178
TEST_TIME 53 10159
TEST_TIME 54 10075
TEST_TIME 55 10190
TEST_TIME 56 10110
TEST_TIME 57 10065
TEST_TIME 58 10062
TEST_TIME 59 9956
TEST_TIME 60 9897
TEST_TIME 61 9842
TEST_TIME 62 9760
TEST_TIME 63 9682
TEST_TIME 64 9654
TEST_TIME 65 9696
TEST_TIME 66 9760
TEST_TIME 67 9725
TEST_TIME 68 9681
TEST_TIME 69 9654
TEST_TIME 70 9651
TEST_TIME 71 9969
TEST_TIME 72 9941
TEST_TIME 73 9962
TEST_TIME 74 9993
TEST_TIME 75 9964
TEST_TIME 76 9869
TEST_TIME 77 9791
TEST_TIME 78 9706
TEST_TIME 79 9713
TEST_TIME 80 9679
TEST_TIME 81 9686
TEST_TIME 82 9650
TEST_TIME 83 9576
TEST_TIME 84 9544
TEST_TIME 85 9488
TEST_TIME 86 9446
TEST_TIME 87 9460
TEST_TIME 88 9467
TEST_TIME 89 9430
TEST_TIME 90 9467
TEST_TIME 91 9505
TEST_TIME 92 9478
TEST_TIME 93 9444
TEST_TIME 94 9444
TEST_TIME 95 9451
TEST_TIME 96 9472
TEST_TIME 97 9525
TEST_TIME 98 9610
TEST_TIME 99 9706
TEST_TIME 100 9655
TEST_TIME 101 9598
TEST_TIME 102 9576
TEST_TIME 103 9534
TEST_TIME 104 9468
TEST_TIME 105 9465
TEST_TIME 106 9397
TEST_TIME 107 9353
TEST_TIME 108 9356
TEST_TIME 109 9329
TEST_TIME 110 9292
TEST_TIME 111 9271
TEST_TIME 112 9264
TEST_TIME 113 9279
TEST_TIME 114 9250
TEST_TIME 115 9232
TEST_TIME 116 9240
TEST_TIME 117 9212
TEST_TIME 118 9251
TEST_TIME 119 9257
TEST_TIME 120 9233

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-02-2023 10:39:01 to 08-02-2023 10:39:04.

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 1525903 1525903 0 0.00 0 24242 13 0 0
doc_list_biud 1525891 1525891 0 0.00 0 44952 46 0 0
sp_add_invoice_to_stock 5073 4909 164 3.23 57 183951 25675 0 164
sp_cancel_adding_invoice 5776 5776 0 0.00 5 111158 1169 0 0
sp_cancel_client_order 1783 1783 0 0.00 6 8039 393 0 0
sp_cancel_customer_reserve 565250 565250 0 0.00 1 44956 109 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 5986 149 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 4 7543 152 0 0
sp_cancel_supplier_invoice 6390 6375 15 0.23 3 14024 1008 0 15
sp_cancel_supplier_order 423 423 0 0.00 9 1421564 182258 0 0
sp_cancel_write_off 9725 9725 0 0.00 2 14398 182 0 0
sp_client_order 20395 20395 0 0.00 5 18031 490 0 0
sp_customer_reserve 455238 455238 0 0.00 1 71321 246 0 0
sp_fill_shopping_cart 31054 31054 0 0.00 2 13501 78 0 0
sp_fill_shopping_cart_clo_res 455217 455217 0 0.00 0 17832 19 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 14855 245 0 0
sp_kill_cost_storno 16639 16639 0 0.00 0 6507 72 0 0
sp_kill_qstorno_ret_qs2qd 1147023 1147023 0 0.00 0 44944 47 0 0
sp_kill_qty_storno 604158 604158 0 0.00 0 44952 107 0 0
sp_lock_selected_doc 20578 20578 0 0.00 0 8360 9 0 0
sp_make_cost_storno 27612 27612 0 0.00 0 24237 81 0 0
sp_multiply_rows_for_pdistr 27610 27610 0 0.00 0 7720 35 0 0
sp_multiply_rows_for_qdistr 20395 20395 0 0.00 0 10382 198 0 0
sp_pay_from_customer 6969 6969 0 0.00 5 11849 172 0 0
sp_pay_to_supplier 5814 5814 0 0.00 7 24259 196 0 0
sp_payment_common 12782 12782 0 0.00 2 7711 49 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 8030 322 0 0
sp_qd_handle_on_invoice_upd_sts 10657 10657 0 0.00 0 16874 509 0 0
sp_qd_handle_on_reserve_upd_sts 9945 9945 0 0.00 0 14105 66 0 0
sp_reserve_write_off 9949 9949 0 0.00 8 17699 283 0 0
sp_supplier_invoice 5323 5323 0 0.00 8 93937 2967 0 0
sp_supplier_order 5348 5348 0 0.00 6 47669 1414 0 0
srv_aggregate_perf_data 101 101 0 0.00 2660 204299 17223 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 668 114240 31019 0 0
srv_find_qd_qs_mism 1058077 1058077 0 0.00 0 14971 10 0 0
srv_make_invnt_saldo 479 479 0 0.00 64 109510 4060 0 0
srv_make_money_saldo 919 919 0 0.00 1 11057 328 0 0
srv_recalc_idx_stat 4 4 0 0.00 79117 425849 241027 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4911 4911 0 0.00 1 13220 895 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 10599 493 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 2105 26 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 662 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 7072 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 1210 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1185 39 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 99789 6993 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 2956 24 0 0
t$perf-norm:sp_client_order 20396 20396 0 0.00 0 6221 34 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 3206 16 0 0
t$perf-norm:sp_pay_from_customer 6969 6969 0 0.00 0 9365 21 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 5664 21 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 7588 26 0 0
t$perf-norm:sp_supplier_invoice 5323 5323 0 0.00 0 5772 31 0 0
t$perf-norm:sp_supplier_order 5348 5348 0 0.00 0 5955 31 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 459 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 247 7 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 2011 16 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 1476 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 150 49 0 0
v_add_invoice_to_stock 10086 9758 328 3.25 0 4323 26 0 328
v_all_customers 37284 37284 0 0.00 0 184 4 0 0
v_all_suppliers 30050 30050 0 0.00 0 124 4 0 0
v_all_wares 40780 40780 0 0.00 0 183 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 957 13 0 0
v_cancel_client_order 3566 3566 0 0.00 0 3156 11 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1661 12 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 15820 28 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 3148 20 0 0
v_cancel_supplier_invoice 810 780 30 3.70 0 6746 41 0 30
v_cancel_supplier_order 846 846 0 0.00 0 1281 12 0 0
v_cancel_write_off 1928 1928 0 0.00 0 14369 37 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 7665 6 0 0
v_max_id_clo_res 989 989 0 0.00 0 5027 20 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 6245 9 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 4081 10 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 2925 8 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 2251 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 4020 13 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 7660 9 0 0
v_min_non_paid_invoice 5813 5813 0 0.00 0 5386 18 0 0
v_min_non_paid_realizn 6969 6969 0 0.00 0 7705 21 0 0
v_our_firm 10070 10070 0 0.00 0 69 4 0 0
v_reserve_write_off 19890 19890 0 0.00 0 17649 12 0 0
x_lock_depdocs_on_canc_invoice 11761 11761 0 0.00 0 14830 124 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 4884 374 0 0
x_make_qstorno_1000_1200 5348 5348 0 0.00 2 47654 1258 0 0
x_make_qstorno_1200_2000 5323 5323 0 0.00 3 93913 2755 0 0
x_make_qstorno_2100_3300 455238 455238 0 0.00 0 71320 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 10:39:05 to 08-02-2023 10:39:09.

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:28 6 8192000000 8805681328 8819630080 9226112 0.112 4 2 2 0 9373776 111888 35776
08:08:29 17433 8192000000 9720630064 10017308672 795036032 8.846 100 85 170 0 823176256 7687840 20452384
08:09:55 22642 8192000000 9757673296 10065899520 834710336 9.247 100 87 170 1 867785072 11960800 21113936
08:11:20 12624 8192000000 9793773920 10114940928 864836384 9.548 100 78 156 0 900548848 17200656 18511808
08:12:40 8867 8192000000 9854355232 10187051008 913796560 10.035 100 75 150 0 962359968 32015072 16548336
08:13:55 5027 8192000000 9927460480 10276757504 967335104 10.561 100 89 173 0 1050665568 64623856 18706608
08:15:03 1678 8192000000 9965674096 10316890112 994914784 10.829 100 96 187 0 1076618208 63027040 18676384
08:16:07 1988 8192000000 9984524992 10345549824 1017988624 11.053 100 87 167 1 1099316272 65188880 16138768
08:17:11 1109 8192000000 10027371648 10399416320 1042387776 11.288 100 94 182 1 1140670432 79772848 18509808
08:18:13 1021 8192000000 10026595808 10402373632 1058963472 11.447 100 94 174 2 1147625888 71583296 17079120
08:19:16 1432 8192000000 10020976320 10402447360 1064939424 11.504 100 90 175 1 1144144896 62132528 17072944
08:20:18 1094 8192000000 10048920432 10432925696 1070714368 11.559 100 92 171 2 1170033360 82574576 16744416
08:21:20 1051 8192000000 10047100736 10434506752 1076173920 11.611 100 87 162 1 1169934928 77590704 16170304
08:22:22 7415 8192000000 10028604208 10422587392 1079802560 11.646 100 65 126 0 1154821472 63557856 11461056
08:23:30 1082 8192000000 10036023776 10426195968 1086468960 11.709 100 93 175 1 1156140992 52218240 17453792
08:24:32 1159 8192000000 10054690832 10440318976 1086739968 11.712 100 95 183 0 1177326368 71408192 19178208
08:25:34 1146 8192000000 10070101344 10460893184 1090709824 11.749 100 98 190 0 1193133200 81801840 20621536
08:26:36 1116 8192000000 10044916336 10439053312 1096379888 11.803 100 83 158 1 1168800816 55570336 16850592
08:27:38 4568 8192000000 10017459104 10413711360 1094011584 11.781 100 68 123 2 1141900528 36217904 11671040
08:28:43 1079 8192000000 10069488320 10464309248 1100412432 11.842 100 84 162 0 1188643392 71931184 16299776
08:29:45 860 8192000000 10058432000 10447499264 1088318416 11.727 100 87 166 0 1180345440 74078816 17948208
08:30:48 1180 8192000000 10043041616 10423836672 1070773152 11.559 100 96 187 0 1161079808 71669888 18636768
08:31:50 972 8192000000 10018910944 10398457856 1063905360 11.494 100 94 183 2 1141804976 59639504 18260112
08:32:52 922 8192000000 9956783008 10322583552 1022514416 11.096 98 88 165 3 1074670576 35151504 17004656
08:33:54 968 8192000000 10018920480 10396909568 1051624080 11.376 100 92 174 1 1144841120 73729408 19487632
08:34:56 1603 8192000000 10026003248 10409832448 1048461248 11.346 100 83 165 1 1153221792 88249232 16511312
08:35:59 1219 8192000000 10024979584 10403557376 1047717712 11.339 100 97 180 2 1148991792 82295696 18978384
08:37:01 4635 8192000000 9998971664 10385309696 1042265328 11.286 100 60 112 1 1113854704 60510096 11079280
08:38:12 6997 8192000000 10010585808 10394275840 1066655504 11.520 99 69 127 1 1124152816 45135328 12361984
08:39:23 1873 8192000000 10039868272 10424172544 1086822784 11.712 100 74 134 0 1162328784 62353232 13152768
08:40:30 6323 8192000000 10071599840 10463567872 1101967456 11.856 100 68 134 0 1187726608 71416192 14342960
08:41:51 2409 8192000000 10118470064 10510426112 1117584384 12.004 100 79 149 0 1234816672 102220224 15012064
08:42:58 957 8192000000 10153573440 10541481984 1122965680 12.055 100 96 182 2 1274330672 130964896 20400096
08:44:01 13507 8192000000 10174554320 10593427456 1142688320 12.241 100 71 140 1 1281306736 123851664 14766752
08:45:19 3263 8192000000 10216319104 10619109376 1156576528 12.371 100 88 169 1 1331101552 155900160 18624864
08:46:24 4293 8192000000 10239491584 10646302720 1167898064 12.477 100 72 142 1 1356956176 173430832 15627280
08:47:33 5579 8192000000 10272025184 10680320000 1176915024 12.561 100 78 144 1 1389733312 196830336 15987952
08:48:42 2603 8192000000 10328888560 10744692736 1189621568 12.680 100 82 159 1 1440052880 232077872 18353440
08:49:48 1477 8192000000 10362942960 10770419712 1190744336 12.690 100 97 188 2 1473801248 260675632 22381280
08:50:51 1261 8192000000 10390227632 10810630144 1194091904 12.721 100 85 169 0 1504954304 291796384 19066016
08:51:55 1275 8192000000 10427893008 10835886080 1195295408 12.733 100 94 179 2 1542179536 326926144 19957984
08:53:02 835 8192000000 10457950944 10865119232 1195409952 12.734 100 96 184 0 1569432256 351180592 22841712
08:54:08 888 8192000000 10484032176 10900561920 1194536352 12.726 100 95 177 2 1589427440 373575952 21315136
08:55:12 860 8192000000 10490541248 10917765120 1198892912 12.766 100 92 182 1 1600335808 379427360 22015536
08:56:17 879 8192000000 10501691712 10920001536 1197093968 12.749 100 92 175 1 1613152912 394370096 21688848
08:57:22 659 8192000000 10525937360 10952503296 1200803408 12.784 100 92 178 1 1631826128 409288208 21734512
08:58:26 1112 8192000000 10560018048 10992967680 1188612928 12.670 100 92 177 0 1662245280 451514400 22117952
08:59:29 707 8192000000 10587300768 11034755072 1196631200 12.745 100 96 189 0 1695524848 475983728 22909920
09:00:33 885 8192000000 10558874016 11027902464 1190211152 12.685 100 99 191 2 1661881568 447515504 24154912
09:01:38 2773 8192000000 10564006432 10985172992 1192702944 12.709 100 72 143 0 1673500000 463224816 17572240
09:02:55 9786 8192000000 10556694656 11032612864 1193617104 12.717 100 55 108 2 1698592640 490650384 14325152
09:04:16 21811 8192000000 10606168544 11054714880 1187324608 12.658 99 46 90 0 1699285712 500200576 11760528
09:05:39 13548 8192000000 10587396624 11056336896 1186518256 12.651 100 39 76 0 1707742320 510348272 10875792
09:06:59 16792 8192000000 10535250512 11036753920 1169984064 12.497 100 37 74 1 1656566992 476575856 10007072
09:08:39 18453 8192000000 10472588480 10991812608 1154806848 12.355 100 32 63 0 1579998752 416614672 8577232
09:09:58 22321 8192000000 10303277808 10779369472 1141438816 12.229 100 26 52 0 1416435536 268328976 6667744
09:11:22 89767 8192000000 10073320944 10554429440 1103771568 11.873 96 11 21 0 1148661376 43033520 1856288
09:13:53 36661 8192000000 10130680240 10614415360 1148459344 12.295 100 19 34 0 1250989712 99367040 3163328
09:15:29 50265 8192000000 10108824848 10550472704 1108244960 11.916 97 53 96 2 1143659648 24332416 11082272
09:17:21 90573 8192000000 10081590304 10536312832 1118187280 12.010 98 19 38 1 1194491520 72689136 3615104
09:19:53 9345 8192000000 10078756016 10495062016 1140064448 12.216 100 77 144 2 1172941824 18276368 14601008
09:21:10 114240 8192000000 10113056032 10588798976 1110760496 11.940 97 39 78 0 1200648736 81554000 8334240
09:24:11 40939 8192000000 10117403536 10552975360 1151223792 12.321 100 39 74 0 1209427008 49940592 8262624
09:25:54 28027 8192000000 10134019104 10583199744 1144678784 12.260 99 34 66 0 1247913456 95912256 7322416
09:27:25 10368 8192000000 10143143872 10586828800 1156166864 12.367 100 80 153 4 1241748128 68472480 17108784
09:28:36 47635 8192000000 10136332464 10616074240 1144013792 12.253 99 19 38 0 1219951840 71717152 4220896
09:30:25 109651 8192000000 10142681248 10621288448 1097623264 11.815 95 14 28 0 1183648496 82651264 3373968
09:33:16 65094 8192000000 10085128320 10560802816 1118204496 12.010 98 21 42 0 1164474128 41778592 4491040
09:35:40 76415 8192000000 10077613696 10558443520 1136352016 12.181 100 17 33 0 1166918688 27288448 3278224
09:38:03 66801 8192000000 10056738976 10545221632 1121471552 12.041 98 13 26 1 1167087184 43403392 2212240
09:40:10 20519 8192000000 10105618576 10553323520 1140080704 12.216 99 34 68 0 1196882576 50145808 6656064
09:41:33 41378 8192000000 10134526768 10605301760 1124938048 12.074 98 28 56 0 1239480032 108780208 5761776
09:43:15 37538 8192000000 10127026448 10573889536 1121433520 12.041 98 24 48 0 1231755120 104233264 6088336
09:45:03 28685 8192000000 10196870800 10636771328 1140172416 12.217 99 38 76 0 1280347136 132122016 8052704
09:46:32 28810 8192000000 10208256064 10656063488 1108782896 11.921 97 26 52 1 1283912704 169275040 5854768
09:48:06 53047 8192000000 10095960768 10634797056 1106343824 11.898 99 31 62 0 1229539056 116333936 6861296
09:50:00 54060 8192000000 10090312048 10533244928 1119871008 12.026 99 27 52 0 1189733088 64600080 5262000
09:51:55 45169 8192000000 10072418672 10502131712 1112086672 11.952 98 37 75 2 1152420432 32991520 7342240
09:53:42 78612 8192000000 10110380192 10551083008 1136708416 12.185 100 46 90 1 1199101840 53034160 9359264
09:56:02 35244 8192000000 10101324368 10579800064 1132189088 12.142 100 25 50 0 1202314112 64509456 5615568
09:57:40 59798 8192000000 10061652656 10572210176 1112108176 11.952 98 5 10 0 1182033776 68811424 1114176
09:59:44 49322 8192000000 10037559568 10489405440 1111590112 11.947 99 21 39 2 1148709952 32816816 4303024
10:02:36 54567 8192000000 10075508144 10539433984 1131227968 12.133 100 10 18 0 1173101376 39673904 2199504
10:04:31 51874 8192000000 10041976768 10510180352 1092364496 11.765 97 20 40 0 1141352128 44372272 4615360
10:06:24 5699 8192000000 10100160544 10533060608 1114786480 11.978 98 50 99 1 1186204272 60902640 10515152
10:07:31 25820 8192000000 10104766608 10551652352 1124170560 12.066 99 35 70 0 1198699216 68006256 6522400
10:09:01 31336 8192000000 10110162816 10548584448 1140858784 12.224 100 33 64 0 1211070352 63827328 6384240
10:10:39 9062 8192000000 10161112416 10597711872 1145127168 12.264 99 56 111 0 1242752224 86209312 11415744
10:11:50 42110 8192000000 10208033664 10682884096 1161205760 12.415 100 44 86 0 1356589840 186190288 9193792
10:13:41 32751 8192000000 10205426080 10661769216 1106848656 11.903 95 22 44 0 1231705920 119535728 5321536
10:15:19 64293 8192000000 10131870000 10673094656 1136538848 12.183 99 12 25 0 1274401392 135240416 2622128
10:17:34 98091 8192000000 10089866800 10574540800 1147043568 12.282 100 32 59 0 1216379904 63034464 6301872
10:21:11 65463 8192000000 10110658928 10569453568 1137761536 12.194 98 10 19 0 1188027040 48766624 1498880
10:23:17 45653 8192000000 10092801200 10579152896 1142431264 12.238 99 16 25 1 1201449584 56743472 2274848
10:25:10 56598 8192000000 10080312336 10589495296 1144099088 12.254 99 14 28 1 1195407216 48448624 2859504
10:27:15 30555 8192000000 10111876160 10560720896 1141204256 12.227 99 29 58 1 1209028944 61835344 5989344
10:28:53 29093 8192000000 10183218624 10639466496 1164794960 12.448 100 35 70 0 1257816800 84859440 8162400
10:30:29 38109 8192000000 10183674256 10657898496 1144845696 12.261 98 35 70 0 1271915056 119386192 7683168
10:32:08 18749 8192000000 10209211712 10658713600 1141597408 12.231 99 43 82 0 1300941280 149885472 9458400
10:33:34 30749 8192000000 10246771200 10689396736 1151692864 12.325 99 41 82 0 1333685472 172830816 9161792
10:35:05 17438 8192000000 10261492896 10713546752 1151085616 12.320 100 51 102 0 1352457856 190074448 11297792
10:36:23 15044 8192000000 10208330416 10650968064 1138578720 12.202 99 37 74 0 1296870576 149634848 8657008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-02-2023 10:39:11 to 08-02-2023 10:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-02-2023 10:39:29 to 08-02-2023 10:39:31.

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          206909
MON$OLDEST_ACTIVE               221806
MON$OLDEST_SNAPSHOT             221806
MON$NEXT_TRANSACTION            221806
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                       2077024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2898 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2898 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2898 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2898 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Feb  8 10:37:38 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

08.02.23 10:39:32. 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
2023-02-08 10:39:35 No severe PSQL-related problems occured
08.02.23 10:39:36. End of report.