Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 04:40
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 15877080 kB
MemAvailable 31552940 kB
Buffers 20 kB
Cached 15790412 kB
SwapCached 2704 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-04-21 04:37:53
DTS_END                         2022-04-21 04:37:16.1190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52430
TRN_ID                          216112
ATT_ID                          259
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: 8587.53 29850 1030504
customer order: creation 166.82 502 20018
customer order: refuse 14.18 543 1701
order to supplier: creation 43.60 1592 5232
order to supplier: removal 3.13 201772 376
invoice (draft): creation 43.87 3973 5264
invoice (draft): removal 45.91 1281 5509
invoice accept: apply 41.25 29525 4950
invoice accept: cancel 41.23 1622 4948
customer reserve: creation 3850.40 273 462048
customer reserve: removal 4052.25 119 486270
realization accept: apply 80.36 333 9643
realization accept: cancel 78.90 199 9468
payment from customer: creation 56.97 212 6836
payment from customer: removal 5.73 148 688
payment to supplier: creation 46.45 248 5574
payment to supplier: removal 3.53 177 423
service: total inventory turnovers 3.69 5211 443
service: total monetary turnovers 8.36 424 1003
service: aggregate perf. data 0.87 20174 104
service: refresh index statistics 0.05 328675 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 04:40:22 to 21-04-2022 04:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8297
TEST_TIME 2 7279
TEST_TIME 3 6546
TEST_TIME 4 6402
TEST_TIME 5 6244
TEST_TIME 6 6274
TEST_TIME 7 6539
TEST_TIME 8 6262
TEST_TIME 9 6587
TEST_TIME 10 6750
TEST_TIME 11 7084
TEST_TIME 12 7216
TEST_TIME 13 7280
TEST_TIME 14 7489
TEST_TIME 15 7419
TEST_TIME 16 7421
TEST_TIME 17 7647
TEST_TIME 18 7872
TEST_TIME 19 8220
TEST_TIME 20 8181
TEST_TIME 21 8214
TEST_TIME 22 8320
TEST_TIME 23 8268
TEST_TIME 24 8430
TEST_TIME 25 8387
TEST_TIME 26 8329
TEST_TIME 27 8530
TEST_TIME 28 8538
TEST_TIME 29 8457
TEST_TIME 30 8372
TEST_TIME 31 8473
TEST_TIME 32 8753
TEST_TIME 33 8955
TEST_TIME 34 8904
TEST_TIME 35 9203
TEST_TIME 36 9364
TEST_TIME 37 9871
TEST_TIME 38 10000
TEST_TIME 39 9956
TEST_TIME 40 10220
TEST_TIME 41 10895
TEST_TIME 42 10930
TEST_TIME 43 10706
TEST_TIME 44 10478
TEST_TIME 45 10466
TEST_TIME 46 10373
TEST_TIME 47 10236
TEST_TIME 48 10223
TEST_TIME 49 10079
TEST_TIME 50 9983
TEST_TIME 51 9845
TEST_TIME 52 9797
TEST_TIME 53 9687
TEST_TIME 54 9558
TEST_TIME 55 9558
TEST_TIME 56 9509
TEST_TIME 57 9400
TEST_TIME 58 9269
TEST_TIME 59 9254
TEST_TIME 60 9250
TEST_TIME 61 9291
TEST_TIME 62 9194
TEST_TIME 63 9147
TEST_TIME 64 9065
TEST_TIME 65 9090
TEST_TIME 66 9048
TEST_TIME 67 9121
TEST_TIME 68 9057
TEST_TIME 69 9022
TEST_TIME 70 8921
TEST_TIME 71 8845
TEST_TIME 72 8848
TEST_TIME 73 8776
TEST_TIME 74 8803
TEST_TIME 75 8837
TEST_TIME 76 8809
TEST_TIME 77 8835
TEST_TIME 78 8813
TEST_TIME 79 8819
TEST_TIME 80 8866
TEST_TIME 81 8824
TEST_TIME 82 8846
TEST_TIME 83 8895
TEST_TIME 84 8959
TEST_TIME 85 8958
TEST_TIME 86 8971
TEST_TIME 87 8891
TEST_TIME 88 8815
TEST_TIME 89 8857
TEST_TIME 90 8790
TEST_TIME 91 8775
TEST_TIME 92 8743
TEST_TIME 93 8720
TEST_TIME 94 8676
TEST_TIME 95 8638
TEST_TIME 96 8617
TEST_TIME 97 8544
TEST_TIME 98 8584
TEST_TIME 99 8717
TEST_TIME 100 8710
TEST_TIME 101 8719
TEST_TIME 102 8705
TEST_TIME 103 8794
TEST_TIME 104 8776
TEST_TIME 105 8804
TEST_TIME 106 8825
TEST_TIME 107 8849
TEST_TIME 108 8823
TEST_TIME 109 8873
TEST_TIME 110 8903
TEST_TIME 111 8884
TEST_TIME 112 8842
TEST_TIME 113 8775
TEST_TIME 114 8827
TEST_TIME 115 8777
TEST_TIME 116 8753
TEST_TIME 117 8735
TEST_TIME 118 8724
TEST_TIME 119 8671
TEST_TIME 120 8617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-04-2022 04:40:25 to 21-04-2022 04:40:29.

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 1453014 1453014 0 0.00 0 15540 16 0 0
doc_list_biud 1452998 1452998 0 0.00 0 26292 47 0 0
sp_add_invoice_to_stock 5134 4950 184 3.58 84 234332 29525 0 184
sp_cancel_adding_invoice 4948 4948 0 0.00 6 112067 1622 0 0
sp_cancel_client_order 1701 1701 0 0.00 7 18583 544 0 0
sp_cancel_customer_reserve 486270 486270 0 0.00 1 26303 120 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 3412 149 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 9371 177 0 0
sp_cancel_supplier_invoice 5516 5509 7 0.12 4 17581 1281 0 7
sp_cancel_supplier_order 376 376 0 0.00 16 1741581 201773 0 0
sp_cancel_write_off 9468 9468 0 0.00 2 10755 200 0 0
sp_client_order 20018 20018 0 0.00 6 16248 503 0 0
sp_customer_reserve 462048 462048 0 0.00 1 84486 273 0 0
sp_fill_shopping_cart 30499 30499 0 0.00 1 41084 105 0 0
sp_fill_shopping_cart_clo_res 462015 462015 0 0.00 0 20829 21 0 0
sp_get_clo_for_invoice 4902 4902 0 0.00 0 52548 385 0 0
sp_kill_cost_storno 15527 15527 0 0.00 0 10685 96 0 0
sp_kill_qstorno_ret_qs2qd 987893 987893 0 0.00 0 23393 51 0 0
sp_kill_qty_storno 522814 522814 0 0.00 0 26289 120 0 0
sp_lock_selected_doc 20212 20212 0 0.00 0 12821 10 0 0
sp_make_cost_storno 26954 26954 0 0.00 0 13893 100 0 0
sp_multiply_rows_for_pdistr 26952 26952 0 0.00 0 8042 45 0 0
sp_multiply_rows_for_qdistr 20018 20018 0 0.00 0 16181 160 0 0
sp_pay_from_customer 6836 6836 0 0.00 5 13486 212 0 0
sp_pay_to_supplier 5574 5574 0 0.00 7 15948 248 0 0
sp_payment_common 12409 12409 0 0.00 2 7529 57 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 17871 454 0 0
sp_qd_handle_on_invoice_upd_sts 9850 9850 0 0.00 0 20218 707 0 0
sp_qd_handle_on_reserve_upd_sts 9640 9640 0 0.00 0 14093 75 0 0
sp_reserve_write_off 9643 9643 0 0.00 8 14112 333 0 0
sp_supplier_invoice 5264 5264 0 0.00 11 87861 3974 0 0
sp_supplier_order 5232 5232 0 0.00 7 57057 1592 0 0
srv_aggregate_perf_data 104 104 0 0.00 2448 223388 20175 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 729 116421 32363 0 0
srv_find_qd_qs_mism 981168 981168 0 0.00 0 10375 12 0 0
srv_make_invnt_saldo 443 443 0 0.00 41 69002 5211 0 0
srv_make_money_saldo 1003 1003 0 0.00 1 21747 424 0 0
srv_recalc_idx_stat 6 6 0 0.00 59167 646728 328676 0 0
t$perf-abend:sp_add_invoice_to_stock 184 184 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4959 4959 0 0.00 1 9065 1268 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 6876 604 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 2798 35 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 3398 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 1509 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 647 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 2030 46 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 97918 9755 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 1098 22 0 0
t$perf-norm:sp_client_order 20019 20019 0 0.00 0 13085 47 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1627 20 0 0
t$perf-norm:sp_pay_from_customer 6836 6836 0 0.00 0 6131 25 0 0
t$perf-norm:sp_pay_to_supplier 5574 5574 0 0.00 0 3062 23 0 0
t$perf-norm:sp_reserve_write_off 9643 9643 0 0.00 0 6170 30 0 0
t$perf-norm:sp_supplier_invoice 5264 5264 0 0.00 0 5802 40 0 0
t$perf-norm:sp_supplier_order 5232 5232 0 0.00 0 1525 38 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 479 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 152 11 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 2982 48 0 0
t$perf-norm:srv_make_money_saldo 1003 1003 0 0.00 0 4953 11 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 263 95 0 0
v_add_invoice_to_stock 10160 9792 368 3.62 0 4100 31 0 368
v_all_customers 36532 36532 0 0.00 0 107 5 0 0
v_all_suppliers 28794 28794 0 0.00 0 92 5 0 0
v_all_wares 40030 40030 0 0.00 0 333 5 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 444 18 0 0
v_cancel_client_order 3402 3402 0 0.00 0 5388 14 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 3331 17 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 1 2489 18 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 746 13 0 0
v_cancel_supplier_invoice 790 776 14 1.77 0 2633 33 0 14
v_cancel_supplier_order 752 752 0 0.00 0 1222 19 0 0
v_cancel_write_off 2086 2086 0 0.00 0 8442 33 0 0
v_max_id_clo_ord 5227 5227 0 0.00 0 5760 8 0 0
v_max_id_clo_res 968 968 0 0.00 0 11944 36 0 0
v_max_id_ord_sup 5256 5256 0 0.00 0 9489 10 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 479 9 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 6792 11 0 0
v_min_id_clo_ord 5227 5227 0 0.00 0 3066 13 0 0
v_min_id_clo_res 968 968 0 0.00 0 6422 21 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 3759 9 0 0
v_min_non_paid_invoice 5574 5574 0 0.00 0 7524 20 0 0
v_min_non_paid_realizn 6834 6834 0 0.00 0 6903 21 0 0
v_our_firm 10120 10120 0 0.00 0 324 6 0 0
v_reserve_write_off 19280 19280 0 0.00 0 5226 12 0 0
x_lock_depdocs_on_canc_invoice 10069 10069 0 0.00 0 26921 167 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 14905 511 0 0
x_make_qstorno_1000_1200 5232 5232 0 0.00 3 56911 1369 0 0
x_make_qstorno_1200_2000 5264 5264 0 0.00 6 87847 3693 0 0
x_make_qstorno_2100_3300 462048 462048 0 0.00 0 84483 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 04:40:30 to 21-04-2022 04:40:34.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 30377 8192000000 9723449488 10022068224 799714832 8.893 100 91 182 1 829785312 8388656 21681824
02:09:56 23513 8192000000 9753400352 10062069760 826397856 9.163 100 89 178 0 860892880 13478912 21016112
02:11:25 13307 8192000000 9788953072 10108919808 860792320 9.508 100 81 162 2 897099504 17423776 18883408
02:12:43 10552 8192000000 9849238608 10182901760 906874944 9.966 100 79 154 0 961214656 37133936 17205776
02:13:58 4462 8192000000 9897005504 10243395584 941366624 10.306 100 76 150 0 1015017552 57656224 15994704
02:15:04 3236 8192000000 9944859536 10298183680 988257712 10.765 100 87 173 0 1062951312 56585776 18107824
02:16:10 7806 8192000000 9991549168 10355556352 1016220912 11.036 100 77 149 2 1105453360 74481264 14751184
02:17:20 1017 8192000000 10001597984 10368061440 1044284496 11.306 100 95 187 0 1121301248 58582272 18434480
02:18:22 1019 8192000000 10024800336 10397052928 1050815136 11.368 100 95 188 2 1145693680 74776256 20102288
02:19:25 1036 8192000000 10037066640 10413338624 1055615648 11.415 100 95 186 0 1155987872 81509520 18862704
02:20:28 1199 8192000000 10022280736 10402201600 1065752176 11.511 100 91 180 2 1142051360 58137008 18162176
02:21:30 1547 8192000000 10033253376 10416029696 1069282368 11.545 100 86 159 1 1155536432 69722128 16531936
02:22:33 4416 8192000000 10040936784 10426146816 1072363888 11.575 100 80 149 0 1152329616 65040256 14925472
02:23:38 1452 8192000000 10055619600 10441617408 1077002192 11.619 100 98 191 1 1177593936 81063824 19527920
02:24:41 1218 8192000000 10058183344 10442047488 1086134320 11.706 100 98 189 0 1178806848 74247440 18425088
02:25:43 1046 8192000000 10053414832 10440437760 1091945376 11.761 100 87 162 1 1171926336 63174976 16805984
02:26:45 1231 8192000000 10060134864 10447245312 1091225488 11.754 100 96 184 1 1185255408 75355376 18674544
02:27:47 1279 8192000000 10060084704 10447392768 1097623664 11.815 100 93 179 1 1180927680 64772096 18531920
02:28:50 1179 8192000000 10029450928 10415419392 1084479392 11.690 99 91 173 1 1150692240 48714592 17498256
02:29:52 1063 8192000000 10028896528 10412044288 1076744576 11.616 100 94 180 3 1150713264 55474720 18493968
02:30:55 1629 8192000000 10061174544 10445131776 1085109728 11.696 100 94 181 3 1178146768 76173984 16863056
02:31:58 1205 8192000000 10029753200 10410299392 1071337168 11.565 100 83 158 0 1155482688 68347120 15798400
02:33:00 1212 8192000000 10023974816 10399866880 1063430368 11.489 100 94 179 1 1141408832 60152576 17825888
02:34:03 1606 8192000000 10002514640 10386866176 1054620384 11.405 100 73 140 0 1126861552 57615792 14625376
02:35:06 2633 8192000000 9993099152 10371891200 1046684608 11.329 100 72 132 3 1103473760 42416160 14372992
02:36:10 11992 8192000000 9978988768 10362789888 1024580752 11.116 99 54 108 2 1108520832 72945408 10994672
02:37:23 5336 8192000000 9993040736 10368528384 1045487072 11.317 100 73 139 1 1115879488 55552128 14840288
02:38:37 2361 8192000000 10008569424 10384687104 1061804176 11.474 100 78 149 0 1124554096 47175264 15574656
02:39:47 18178 8192000000 10071566928 10460610560 1081021456 11.657 100 68 127 0 1179974368 86238432 12714480
02:41:09 1435 8192000000 10102278544 10485710848 1103068176 11.867 100 91 178 0 1219185264 97434000 18683088
02:42:14 1908 8192000000 10136518000 10522656768 1111047408 11.942 100 79 150 0 1248413984 121153472 16213104
02:43:20 12590 8192000000 10122801232 10529751040 1125731680 12.081 100 60 117 0 1242533104 104017600 12783824
02:44:39 1294 8192000000 10173728896 10565345280 1139775312 12.213 100 87 158 4 1283764528 126852608 17136608
02:45:44 1586 8192000000 10199191472 10606190592 1149244320 12.302 100 79 158 1 1322150912 155595648 17310944
02:46:56 986 8192000000 10240837504 10642112512 1152240240 12.331 100 93 170 3 1354565664 181680304 20645120
02:48:02 1267 8192000000 10198680688 10619953152 1159510304 12.399 100 89 167 1 1314070960 137025200 17535456
02:49:08 5407 8192000000 10232623536 10647269376 1160580000 12.409 100 71 140 0 1342894848 167002304 15312544
02:50:20 1500 8192000000 10251655664 10660634624 1172658944 12.522 100 94 181 0 1362548560 169470736 20418880
02:51:25 1198 8192000000 10273169008 10674352128 1166840912 12.467 100 95 185 0 1383059744 195939664 20279168
02:52:30 912 8192000000 10306011840 10707808256 1168314112 12.481 99 97 191 0 1423949024 232909680 22725232
02:53:34 769 8192000000 10333267008 10753921024 1187748672 12.662 100 97 192 1 1442224192 232789344 21686176
02:54:41 1711 8192000000 10338484416 10777210880 1185543888 12.642 100 95 187 0 1453891328 246686272 21661168
02:55:46 858 8192000000 10336332880 10801639424 1177567216 12.568 100 94 179 1 1446635056 248036720 21031120
02:56:51 2565 8192000000 10335507968 10743644160 1178172528 12.573 100 80 152 0 1447126976 251335392 17619056
02:57:58 2671 8192000000 10357084016 10777956352 1168247808 12.480 99 83 164 0 1466086800 279094416 18744576
02:59:05 832 8192000000 10394366560 10806099968 1178669360 12.578 100 95 186 1 1501791664 301412592 21709712
03:00:10 941 8192000000 10402023056 10812096512 1192327008 12.705 100 96 189 0 1508970176 295103392 21539776
03:01:13 902 8192000000 10440030000 10863607808 1182330656 12.612 100 95 188 0 1545601888 341632672 21638560
03:02:18 895 8192000000 10472996848 10888388608 1185887488 12.645 100 96 183 2 1572761296 364879152 21994656
03:03:26 978 8192000000 10502508960 10922086400 1195518992 12.735 100 97 186 0 1607708736 387815792 24373952
03:04:30 1028 8192000000 10518558816 10954522624 1202145984 12.796 100 98 190 1 1621638688 395426192 24066512
03:05:34 1127 8192000000 10561264928 10968092672 1192280048 12.705 100 94 188 3 1659036288 444676336 22079904
03:06:36 3122 8192000000 10564489504 10974220288 1157531296 12.380 99 87 174 1 1659729488 481430368 20767824
03:07:42 1156 8192000000 10622992272 11054784512 1185250560 12.639 100 97 192 0 1726501776 518511984 22739232
03:08:47 842 8192000000 10612987664 11096002560 1194046416 12.721 100 89 175 0 1713164544 497122752 21995376
03:09:53 716 8192000000 10579746480 11036954624 1192864864 12.710 100 98 192 0 1678379104 462499088 23015152
03:10:56 9781 8192000000 10595658352 11051044864 1190852624 12.691 100 51 102 0 1690718128 486835248 13030256
03:12:58 2575 8192000000 10549032400 11009368064 1176034144 12.553 100 62 124 2 1660015376 467637680 16343552
03:14:06 16458 8192000000 10476351296 10981707776 1164028352 12.441 99 34 68 0 1574936160 401728880 9178928
03:15:44 41755 8192000000 10377998528 10865180672 1158658256 12.391 99 24 48 1 1484276000 319454224 6163520
03:18:11 36306 8192000000 10122364512 10693169152 1144814688 12.261 100 20 40 1 1339604432 189516832 5272912
03:19:49 91371 8192000000 10134188656 10569408512 1156150752 12.367 100 28 52 2 1208940672 47011760 5778160
03:22:22 116421 8192000000 10106217024 10590175232 1104787600 11.883 96 16 32 0 1186119520 78145264 3186656
03:25:21 82665 8192000000 10063539136 10507296768 1121841712 12.044 98 23 44 2 1155405488 29155600 4408176
03:27:48 114121 8192000000 10079517440 10531782656 1100390288 11.841 96 26 50 0 1142285152 36062112 5832752
03:31:13 74804 8192000000 10106875184 10571259904 1096735824 11.807 96 30 57 0 1163665120 60807744 6121552
03:33:58 102075 8192000000 10097574384 10531434496 1126412192 12.088 99 38 76 0 1164180016 29807328 7960496
03:36:43 102689 8192000000 10095020992 10531917824 1094208528 11.783 96 40 76 2 1171754848 68721776 8824544
03:39:32 41499 8192000000 10067044928 10522079232 1129203584 12.114 100 14 28 1 1184018016 51198112 3616320
03:41:15 2444 8192000000 10076252784 10514808832 1136437504 12.182 100 52 103 1 1178619600 31553152 10628944
03:42:21 25911 8192000000 10107952656 10559332352 1127833104 12.101 99 28 56 0 1212580256 79024048 5723104
03:43:51 110251 8192000000 10063230480 10547920896 1111344160 11.945 97 28 55 0 1172051552 55201664 5505728
03:46:45 42206 8192000000 10046978656 10520752128 1128306992 12.105 99 12 25 2 1158420688 27180320 2933376
03:49:04 2141 8192000000 10062405776 10500595712 1143139584 12.245 100 67 133 2 1171930512 11789440 17001488
03:50:08 60242 8192000000 10106202640 10581782528 1126242848 12.086 98 13 26 0 1192065088 63136528 2685712
03:52:13 41011 8192000000 10071079360 10533548032 1097309088 11.812 96 21 37 1 1151721248 50929200 3482960
03:53:59 32291 8192000000 10083190192 10525102080 1117765056 12.006 98 32 64 0 1192211664 66092400 8354208
03:55:35 17003 8192000000 10129765552 10556628992 1141448400 12.229 100 47 94 0 1218324528 67457120 9419008
03:56:57 70493 8192000000 10126809248 10575912960 1127146864 12.094 99 17 34 0 1215425728 84753536 3525328
04:00:13 60512 8192000000 10142731200 10644926464 1142257392 12.237 100 15 30 1 1252638240 106141120 4239728
04:02:44 84234 8192000000 10085988720 10512551936 1122416816 12.050 98 28 54 1 1165160112 36718784 6024512
04:05:08 43461 8192000000 10135866560 10572115968 1134867648 12.167 99 60 114 1 1210441840 62746336 12827856
04:07:10 39933 8192000000 10074007008 10560180224 1127993120 12.102 100 12 24 1 1171763616 41278960 2491536
04:09:14 48626 8192000000 10084775856 10532540416 1135263232 12.171 100 16 32 1 1186011712 47400256 3348224
04:11:03 53680 8192000000 10129504976 10575384576 1131518656 12.136 99 32 64 2 1220792704 82060112 7213936
04:13:01 65928 8192000000 10143998592 10619506688 1127555536 12.098 99 15 30 1 1236990016 105803520 3630960
04:15:22 96925 8192000000 10120608576 10681057280 1128644608 12.109 98 14 28 1 1285998528 154079872 3274048
04:18:07 60809 8192000000 10088618832 10561339392 1099327744 11.831 96 17 34 0 1158607200 55673648 3605808
04:20:16 51547 8192000000 10090700704 10575978496 1122350208 12.049 99 20 40 1 1198714672 72315360 4049104
04:22:12 31297 8192000000 10083011168 10507673600 1075939488 11.609 96 49 96 2 1150717856 64475600 10302768
04:23:56 28024 8192000000 10106442112 10530889728 1110866192 11.941 99 55 105 1 1179742016 57816096 11059728
04:25:41 35660 8192000000 10085974880 10556354560 1117272736 12.001 100 46 92 1 1200561232 74099152 9189344
04:27:24 78734 8192000000 10039684880 10489147392 1080281920 11.650 97 20 39 2 1122467936 37987360 4198656
04:29:53 72896 8192000000 10135690832 10561654784 1127033456 12.093 100 61 122 2 1219471312 79779808 12658048
04:32:22 25722 8192000000 10060431248 10525011968 1122200864 12.048 100 22 44 1 1162765152 36212400 4351888
04:33:48 30019 8192000000 10058805200 10511372288 1112367392 11.955 99 29 58 0 1166628576 48218960 6042224
04:35:24 75857 8192000000 10099331152 10573299712 1109765824 11.930 98 11 22 0 1164244480 51943360 2535296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-04-2022 04:40:36 to 21-04-2022 04:40:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 184 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 04:40:53 to 21-04-2022 04:40:55.

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          215517
MON$OLDEST_ACTIVE               217167
MON$OLDEST_SNAPSHOT             217167
MON$NEXT_TRANSACTION            217167
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                       2126720
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:433: 
:434: mscs-test-161.budzdorov.ru    Thu Apr 21 04:38:08 2022
:435:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:436: 
--- end of diff output ---

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

21.04.22 04:40:56. 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-04-21 04:40:58 No severe PSQL-related problems occured
21.04.22 04:41:00. End of report.