Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 07:39
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 16353732 kB
MemAvailable 31492184 kB
Buffers 0 kB
Cached 15388256 kB
SwapCached 2656 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-14 07:37:11
DTS_END                         2022-05-14 07:37:11.5880
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/37216
TRN_ID                          213071
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: 8949.88 30339 1073986
customer order: creation 157.98 497 18958
customer order: refuse 13.56 392 1627
order to supplier: creation 42.73 1408 5127
order to supplier: removal 3.27 179465 392
invoice (draft): creation 43.48 3348 5218
invoice (draft): removal 51.83 1021 6220
invoice accept: apply 39.20 25794 4704
invoice accept: cancel 46.85 1231 5622
customer reserve: creation 3667.63 242 440116
customer reserve: removal 4606.86 99 552823
realization accept: apply 77.08 269 9250
realization accept: cancel 80.97 176 9716
payment from customer: creation 53.79 173 6455
payment from customer: removal 5.20 118 624
payment to supplier: creation 44.82 199 5378
payment to supplier: removal 3.26 151 391
service: total inventory turnovers 3.68 4024 441
service: total monetary turnovers 6.93 351 831
service: aggregate perf. data 0.74 21053 89
service: refresh index statistics 0.03 366763 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-05-2022 07:39:56 to 14-05-2022 07:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9851
TEST_TIME 2 7401
TEST_TIME 3 6744
TEST_TIME 4 6330
TEST_TIME 5 6306
TEST_TIME 6 6842
TEST_TIME 7 7243
TEST_TIME 8 7104
TEST_TIME 9 7276
TEST_TIME 10 7133
TEST_TIME 11 7237
TEST_TIME 12 7482
TEST_TIME 13 7628
TEST_TIME 14 7616
TEST_TIME 15 7683
TEST_TIME 16 7948
TEST_TIME 17 7971
TEST_TIME 18 7931
TEST_TIME 19 8244
TEST_TIME 20 8274
TEST_TIME 21 8348
TEST_TIME 22 8599
TEST_TIME 23 8815
TEST_TIME 24 8850
TEST_TIME 25 8735
TEST_TIME 26 8593
TEST_TIME 27 8692
TEST_TIME 28 9122
TEST_TIME 29 10069
TEST_TIME 30 10713
TEST_TIME 31 11265
TEST_TIME 32 11603
TEST_TIME 33 11330
TEST_TIME 34 11392
TEST_TIME 35 11179
TEST_TIME 36 11050
TEST_TIME 37 10905
TEST_TIME 38 10985
TEST_TIME 39 10861
TEST_TIME 40 10692
TEST_TIME 41 10601
TEST_TIME 42 10410
TEST_TIME 43 10332
TEST_TIME 44 10195
TEST_TIME 45 10071
TEST_TIME 46 10012
TEST_TIME 47 9934
TEST_TIME 48 9883
TEST_TIME 49 9850
TEST_TIME 50 9805
TEST_TIME 51 9707
TEST_TIME 52 9651
TEST_TIME 53 9515
TEST_TIME 54 9439
TEST_TIME 55 9385
TEST_TIME 56 9323
TEST_TIME 57 9326
TEST_TIME 58 9336
TEST_TIME 59 9303
TEST_TIME 60 9306
TEST_TIME 61 9270
TEST_TIME 62 9497
TEST_TIME 63 9431
TEST_TIME 64 9447
TEST_TIME 65 9378
TEST_TIME 66 9457
TEST_TIME 67 9493
TEST_TIME 68 9456
TEST_TIME 69 9410
TEST_TIME 70 9662
TEST_TIME 71 9572
TEST_TIME 72 9476
TEST_TIME 73 9397
TEST_TIME 74 9392
TEST_TIME 75 9345
TEST_TIME 76 9307
TEST_TIME 77 9322
TEST_TIME 78 9267
TEST_TIME 79 9307
TEST_TIME 80 9268
TEST_TIME 81 9257
TEST_TIME 82 9265
TEST_TIME 83 9261
TEST_TIME 84 9319
TEST_TIME 85 9340
TEST_TIME 86 9285
TEST_TIME 87 9359
TEST_TIME 88 9477
TEST_TIME 89 9460
TEST_TIME 90 9385
TEST_TIME 91 9327
TEST_TIME 92 9295
TEST_TIME 93 9252
TEST_TIME 94 9265
TEST_TIME 95 9232
TEST_TIME 96 9193
TEST_TIME 97 9154
TEST_TIME 98 9137
TEST_TIME 99 9065
TEST_TIME 100 9102
TEST_TIME 101 9077
TEST_TIME 102 9103
TEST_TIME 103 9112
TEST_TIME 104 9088
TEST_TIME 105 9079
TEST_TIME 106 9077
TEST_TIME 107 9140
TEST_TIME 108 9218
TEST_TIME 109 9180
TEST_TIME 110 9206
TEST_TIME 111 9218
TEST_TIME 112 9224
TEST_TIME 113 9224
TEST_TIME 114 9177
TEST_TIME 115 9138
TEST_TIME 116 9093
TEST_TIME 117 9086
TEST_TIME 118 9020
TEST_TIME 119 9039
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 07:40:00 to 14-05-2022 07:40:03.

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 1479216 1479216 0 0.00 0 16871 13 0 0
doc_list_biud 1479216 1479216 0 0.00 0 34296 42 0 0
sp_add_invoice_to_stock 4814 4704 110 2.28 59 207105 25795 0 110
sp_cancel_adding_invoice 5622 5622 0 0.00 5 76250 1231 0 0
sp_cancel_client_order 1627 1627 0 0.00 6 11081 392 0 0
sp_cancel_customer_reserve 552823 552823 0 0.00 1 20962 99 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 4 4166 118 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 6993 151 0 0
sp_cancel_supplier_invoice 6224 6220 4 0.06 2 34304 1021 0 4
sp_cancel_supplier_order 392 392 0 0.00 8 1479473 179465 0 0
sp_cancel_write_off 9716 9716 0 0.00 2 10224 176 0 0
sp_client_order 18958 18958 0 0.00 6 39434 498 0 0
sp_customer_reserve 440116 440116 0 0.00 1 161000 243 0 0
sp_fill_shopping_cart 29299 29299 0 0.00 1 35459 87 0 0
sp_fill_shopping_cart_clo_res 440098 440098 0 0.00 0 18709 20 0 0
sp_get_clo_for_invoice 4665 4665 0 0.00 0 37406 327 0 0
sp_kill_cost_storno 16353 16353 0 0.00 0 13768 77 0 0
sp_kill_qstorno_ret_qs2qd 1121974 1121974 0 0.00 0 34294 43 0 0
sp_kill_qty_storno 590313 590313 0 0.00 0 34296 99 0 0
sp_lock_selected_doc 19199 19199 0 0.00 0 13199 8 0 0
sp_make_cost_storno 25746 25746 0 0.00 0 16829 76 0 0
sp_multiply_rows_for_pdistr 25746 25746 0 0.00 0 9337 39 0 0
sp_multiply_rows_for_qdistr 18958 18958 0 0.00 0 24400 204 0 0
sp_pay_from_customer 6455 6455 0 0.00 6 18367 174 0 0
sp_pay_to_supplier 5378 5378 0 0.00 7 15814 199 0 0
sp_payment_common 11833 11833 0 0.00 2 15256 50 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 1 10968 328 0 0
sp_qd_handle_on_invoice_upd_sts 10285 10285 0 0.00 0 21832 527 0 0
sp_qd_handle_on_reserve_upd_sts 9250 9250 0 0.00 0 11131 57 0 0
sp_reserve_write_off 9250 9250 0 0.00 8 11912 270 0 0
sp_supplier_invoice 5218 5218 0 0.00 9 81654 3349 0 0
sp_supplier_order 5127 5127 0 0.00 8 33666 1408 0 0
srv_aggregate_perf_data 89 89 0 0.00 2201 321462 21053 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 714 167117 40798 0 0
srv_find_qd_qs_mism 1028346 1028346 0 0.00 0 15680 10 0 0
srv_make_invnt_saldo 441 441 0 0.00 54 59909 4024 0 0
srv_make_money_saldo 831 831 0 0.00 1 13370 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 235287 414822 366764 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4704 4704 0 0.00 1 25233 890 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 2 4791 412 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 8556 32 0 0
t$perf-norm:sp_cancel_customer_reserve 608 608 0 0.00 0 931 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 6807 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 4473 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 2841 42 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 116345 7163 0 0
t$perf-norm:sp_cancel_write_off 859 859 0 0.00 0 3784 23 0 0
t$perf-norm:sp_client_order 18958 18958 0 0.00 0 8338 32 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 900 14 0 0
t$perf-norm:sp_pay_from_customer 6455 6455 0 0.00 0 5627 17 0 0
t$perf-norm:sp_pay_to_supplier 5378 5378 0 0.00 0 5472 21 0 0
t$perf-norm:sp_reserve_write_off 9250 9250 0 0.00 0 13024 20 0 0
t$perf-norm:sp_supplier_invoice 5218 5218 0 0.00 0 8205 32 0 0
t$perf-norm:sp_supplier_order 5127 5127 0 0.00 0 8282 32 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 210 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 192 9 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 411 12 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 384 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 179 81 0 0
v_add_invoice_to_stock 9540 9320 220 2.30 0 20820 37 0 220
v_all_customers 34946 34946 0 0.00 0 179 3 0 0
v_all_suppliers 28914 28914 0 0.00 0 82 4 0 0
v_all_wares 37914 37914 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1059 15 0 0
v_cancel_client_order 3254 3254 0 0.00 0 1335 8 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 1323 12 0 0
v_cancel_customer_reserve 1216 1216 0 0.00 0 1114 12 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 6942 20 0 0
v_cancel_supplier_invoice 802 794 8 0.99 0 440 22 0 8
v_cancel_supplier_order 784 784 0 0.00 0 553 10 0 0
v_cancel_write_off 1718 1718 0 0.00 0 4165 25 0 0
v_max_id_clo_ord 5126 5126 0 0.00 0 960 5 0 0
v_max_id_clo_res 932 932 0 0.00 0 3256 20 0 0
v_max_id_ord_sup 5215 5215 0 0.00 0 6158 10 0 0
v_max_non_paid_invoice 1262 1262 0 0.00 0 48 7 0 0
v_max_non_paid_realizn 3249 3249 0 0.00 0 6424 10 0 0
v_min_id_clo_ord 5126 5126 0 0.00 0 8450 11 0 0
v_min_id_clo_res 932 932 0 0.00 0 5686 16 0 0
v_min_id_ord_sup 5215 5215 0 0.00 0 6392 8 0 0
v_min_non_paid_invoice 5378 5378 0 0.00 0 15245 22 0 0
v_min_non_paid_realizn 6455 6455 0 0.00 0 7693 21 0 0
v_our_firm 9380 9380 0 0.00 0 79 4 0 0
v_reserve_write_off 18500 18500 0 0.00 0 11065 14 0 0
x_lock_depdocs_on_canc_invoice 11445 11445 0 0.00 0 25941 130 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 3740 346 0 0
x_make_qstorno_1000_1200 5127 5127 0 0.00 3 33649 1233 0 0
x_make_qstorno_1200_2000 5218 5218 0 0.00 4 77475 3109 0 0
x_make_qstorno_2100_3300 440116 440116 0 0.00 0 160997 212 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-05-2022 07:40:04 to 14-05-2022 07:40: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
05:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:16 27875 8192000000 9715971952 10012684288 795899728 8.855 100 90 180 0 825518560 7395360 22223472
05:09:48 33121 8192000000 9764518080 10074005504 835725040 9.257 100 91 182 1 870378256 12917872 21735344
05:11:23 25645 8192000000 9818234784 10140286976 873604112 9.636 100 89 178 1 922510976 28422560 20484304
05:12:53 10275 8192000000 9871932752 10208923648 925726304 10.153 100 79 158 0 990753904 47987392 17040208
05:14:13 5112 8192000000 9921349712 10270928896 961301488 10.502 100 77 149 0 1031963616 55459120 15203008
05:15:23 1971 8192000000 9986923712 10341056512 1001752720 10.896 100 92 185 0 1103138304 82520192 18865392
05:16:26 1509 8192000000 10021167568 10389868544 1026364976 11.133 100 94 184 3 1137026608 91838128 18823504
05:17:30 920 8192000000 10018791984 10390654976 1050633120 11.367 100 97 192 1 1144153888 74391664 19129104
05:18:32 979 8192000000 10033188160 10410090496 1067739024 11.530 100 91 178 0 1153303984 67949296 17615664
05:19:34 6833 8192000000 10052043328 10431877120 1073600608 11.586 100 88 163 2 1178027696 88089648 16337440
05:20:42 1038 8192000000 10059875536 10444857344 1083193664 11.678 100 93 183 0 1182423632 79784080 19445888
05:21:43 2750 8192000000 10036249760 10421252096 1088428192 11.728 100 79 154 4 1149972192 45796944 15747056
05:22:47 1244 8192000000 10048807648 10443378688 1095086960 11.791 100 71 135 1 1171281984 62024064 14170960
05:23:49 1269 8192000000 10044736096 10436214784 1098084992 11.819 100 90 162 2 1168533904 51942944 18505968
05:24:51 1907 8192000000 10062944880 10455949312 1103189680 11.868 100 80 152 1 1182559824 64644672 14725472
05:25:54 1270 8192000000 10054614688 10444193792 1110268640 11.935 100 90 175 1 1174969584 47873728 16827216
05:26:56 1394 8192000000 10071766464 10462564352 1107410480 11.908 100 91 175 2 1188750512 63289200 18050832
05:27:59 5046 8192000000 10059863168 10458365952 1109173280 11.925 100 80 154 3 1182240816 58700544 14366992
05:29:06 1184 8192000000 10073378256 10459918336 1090959152 11.752 99 96 189 1 1193780208 84634272 18186784
05:30:08 1226 8192000000 10042985216 10427596800 1077349536 11.622 99 96 184 0 1161324848 65094400 18880912
05:31:10 1181 8192000000 10014778096 10395488256 1046629840 11.328 100 97 187 2 1133279232 67356816 19292576
05:32:12 7411 8192000000 9987436880 10365059072 1033066464 11.198 98 68 134 1 1107854112 60777200 14010448
05:33:20 1701 8192000000 9951510768 10323202048 1028679632 11.156 100 85 161 3 1074352880 29846112 15827136
05:34:22 1112 8192000000 9957529328 10329051136 1018756496 11.060 100 75 143 1 1078691184 43278528 16656160
05:35:24 925 8192000000 9984698880 10357587968 1025618016 11.126 100 89 167 1 1110463824 67271360 17574448
05:36:26 9342 8192000000 9984184272 10375675904 1043915792 11.302 100 53 99 1 1115223552 60739520 10568240
05:37:38 9421 8192000000 10003597744 10387230720 1056965920 11.427 100 54 102 0 1126269056 59334880 9968256
05:38:53 2512 8192000000 10025282240 10407948288 1068495104 11.538 100 80 144 2 1142064384 58752800 14816480
05:40:03 12041 8192000000 10051113712 10438324224 1088659392 11.730 100 74 141 1 1166500608 62234288 15606928
05:41:31 18549 8192000000 10112886368 10502045696 1102304256 11.859 100 79 146 1 1231470128 113768688 15397184
05:42:53 1192 8192000000 10137275216 10528202752 1110622064 11.938 100 85 148 1 1254661968 126992032 17047872
05:43:57 1054 8192000000 10185021312 10573565952 1126313792 12.087 100 94 181 1 1299419376 153773760 19331824
05:45:00 2731 8192000000 10166978208 10568364032 1129133328 12.113 100 65 126 1 1285184144 142832656 13218160
05:46:06 5198 8192000000 10210938016 10622558208 1146523728 12.277 100 79 155 0 1327160800 162973408 17663664
05:47:16 5095 8192000000 10242126240 10637344768 1169238848 12.490 100 82 162 4 1353684080 167337808 17107424
05:48:25 899 8192000000 10293827968 10691608576 1178424816 12.576 100 93 181 2 1403428288 205154768 19848704
05:49:29 810 8192000000 10342942752 10753077248 1195240592 12.732 100 98 192 2 1456761120 240126144 21394384
05:50:32 1019 8192000000 10366059888 10775498752 1197178144 12.750 100 95 188 2 1472008944 253346592 21484208
05:51:36 815 8192000000 10391997600 10797510656 1193518944 12.716 100 95 184 1 1501913472 286631088 21763440
05:52:39 2001 8192000000 10451969680 10881867776 1208434640 12.855 100 96 184 0 1562697920 332572288 21690992
05:53:42 1377 8192000000 10462323536 10878357504 1206897280 12.840 100 94 181 0 1569605904 341994816 20713808
05:54:46 702 8192000000 10477805184 10885246976 1199837152 12.775 100 98 188 0 1583476304 361049632 22589520
05:55:51 1703 8192000000 10488457824 10943139840 1205598336 12.828 100 98 195 0 1593192816 364119744 23474736
05:56:56 820 8192000000 10514727264 10929766400 1199807136 12.775 100 96 189 0 1620006048 396446432 23752480
05:58:00 955 8192000000 10500959648 10943909888 1179014192 12.581 99 86 169 0 1606582928 407642464 19926272
05:59:06 902 8192000000 10577790128 11016314880 1204488432 12.818 100 100 197 0 1679962864 451866016 23608416
06:00:12 4189 8192000000 10606283584 11032772608 1204512160 12.818 100 68 136 0 1708424176 486203552 17708464
06:01:29 9352 8192000000 10583684432 11046850560 1201625424 12.791 100 55 110 1 1686356672 470647008 14084240
06:02:52 7997 8192000000 10640896368 11077709824 1195592688 12.735 100 55 109 0 1737459552 527709456 14157408
06:04:05 16593 8192000000 10645867504 11115696128 1180522608 12.595 100 42 82 0 1776577664 584556352 11498704
06:05:34 6123 8192000000 10591904624 11076091904 1169868928 12.496 100 41 82 0 1703815344 522958272 10988144
06:06:57 22972 8192000000 10349859952 10853228544 1141352112 12.228 98 37 74 0 1436595376 286426448 8816816
06:08:24 33571 8192000000 10189608496 10679472128 1135619648 12.174 99 16 30 1 1317501792 177854016 4028128
06:09:59 64833 8192000000 10096972672 10533888000 1058609968 11.443 93 33 63 1 1137458432 71320368 7528096
06:12:07 52444 8192000000 10081097632 10521235456 1115553936 11.985 99 22 44 0 1188486112 67941984 4990192
06:14:05 110864 8192000000 10055326112 10555166720 1072089632 11.572 96 25 47 0 1166281568 88965648 5226288
06:17:06 83338 8192000000 10058309920 10493116416 1069447600 11.547 96 27 52 0 1110208976 35323744 5437632
06:19:30 112052 8192000000 10041887312 10476363776 1107866240 11.912 99 39 77 2 1149966176 34227056 7872880
06:22:23 167117 8192000000 10072906800 10532892672 1098915920 11.827 97 34 67 0 1144449696 38140960 7392816
06:26:17 84262 8192000000 10063027024 10542678016 1103477264 11.871 98 27 54 0 1163231760 53568528 6185968
06:28:42 53746 8192000000 10070108064 10540449792 1092363456 11.765 97 8 16 0 1154808992 60751760 1693776
06:30:35 52130 8192000000 10057300832 10519842816 1111505856 11.947 99 20 40 0 1149604912 33652048 4447008
06:32:31 76998 8192000000 10126365920 10582966272 1144914096 12.262 100 15 30 1 1232107280 83697840 3495344
06:34:48 41627 8192000000 10118428336 10569768960 1116632496 11.995 97 16 32 0 1195410832 75272944 3505392
06:36:35 58484 8192000000 10188446368 10640908288 1133166480 12.151 99 25 50 2 1277255008 138353040 5735488
06:38:34 47264 8192000000 10153203952 10652110848 1132777072 12.148 99 31 60 0 1271289856 131490768 7022016
06:40:33 34892 8192000000 10174139984 10612137984 1140661808 12.222 99 32 64 2 1256117776 108115792 7340176
06:42:11 14820 8192000000 10213986656 10641817600 1146681136 12.278 99 56 107 0 1299291616 139723392 12887088
06:43:34 40063 8192000000 10243474880 10726072320 1166193568 12.461 100 28 56 1 1346752592 173423520 7135504
06:45:18 70896 8192000000 10199186992 10699702272 1136543136 12.183 98 18 36 1 1343509072 202586016 4379920
06:48:27 73668 8192000000 10064246448 10557542400 1142642784 12.240 100 4 8 0 1180002240 36821168 538288
06:51:03 96462 8192000000 10053361840 10525581312 1056403712 11.422 92 5 10 0 1080637040 23674512 558816
06:53:41 38310 8192000000 10088269792 10529058816 1120767696 12.034 99 26 52 0 1180078656 53189968 6120992
06:55:34 22046 8192000000 10109365760 10542948352 1135624096 12.174 100 60 113 0 1203729520 56994336 11111088
06:57:09 34666 8192000000 10089015184 10520739840 1136356032 12.181 100 38 76 0 1190522160 45950528 8215600
06:58:46 42528 8192000000 10120389296 10568978432 1129638352 12.118 99 31 61 0 1202722976 66898848 6185776
07:00:30 54137 8192000000 10085734672 10549067776 1101416720 11.851 98 30 56 2 1173658960 65939536 6302704
07:02:24 47902 8192000000 10106950016 10563022848 1105384864 11.889 99 31 58 0 1160610672 49752944 5472864
07:04:12 69131 8192000000 10109115440 10591768576 1102676704 11.863 98 10 20 0 1227524304 122223856 2623744
07:06:41 120442 8192000000 10072634768 10519425024 1091033840 11.752 97 33 66 0 1173440368 75663696 6742832
07:09:42 20140 8192000000 10073048688 10547236864 1115806512 11.987 100 16 32 0 1176836576 57640064 3390000
07:11:03 35687 8192000000 10046222368 10508029952 1095253872 11.793 99 29 58 0 1166703120 65768944 5680304
07:12:43 64694 8192000000 10066664272 10516119552 1101543120 11.852 98 13 26 0 1136696272 31745552 3407600
07:14:48 62144 8192000000 10062788512 10529726464 1080428160 11.652 97 13 26 0 1142193024 58379504 3385360
07:16:50 86963 8192000000 10096201456 10551971840 1105454544 11.889 99 21 39 2 1154914288 45744688 3715056
07:19:24 41749 8192000000 10062765440 10552741888 1121112784 12.038 99 14 28 0 1171305168 47026448 3165936
07:21:06 40868 8192000000 10132611920 10572738560 1133777552 12.157 99 47 93 1 1205568432 61605536 10185344
07:23:02 43043 8192000000 10159648064 10599944192 1134564992 12.164 99 32 64 0 1244180240 102725664 6889584
07:24:50 79141 8192000000 10064155936 10514853888 1081567936 11.662 95 17 34 1 1130895936 45268768 4059232
07:27:09 54714 8192000000 10101494384 10564263936 1077526000 11.624 96 40 80 1 1166318896 80386208 8406688
07:29:13 161916 8192000000 10061921760 10510725120 1084824736 11.693 96 29 58 0 1155033072 64309872 5898464
07:32:57 71502 8192000000 10103378608 10547257344 1117744992 12.006 99 15 30 1 1175632592 54493808 3393792
07:35:16 36254 8192000000 10121247008 10585812992 1137266880 12.190 100 10 20 1 1229759760 90234384 2258496
07:36:53 39503 8192000000 9496754112 9953927168 593395824 6.754 53 5 10 0 672696848 78323664 977360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-05-2022 07:40:10 to 14-05-2022 07:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-05-2022 07:40:27 to 14-05-2022 07:40:29.

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          213278
MON$OLDEST_ACTIVE               213279
MON$OLDEST_SNAPSHOT             213279
MON$NEXT_TRANSACTION            213279
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                       2057248
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Sat May 14 07:37:39 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Sat May 14 07:37:49 2022
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Sat May 14 07:37:53 2022
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

14.05.22 07:40:30. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-14 07:40:33 No severe PSQL-related problems occured
14.05.22 07:40:34. End of report.