Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.11.2021 04:37
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 16687364 kB
MemAvailable 31501692 kB
Buffers 20 kB
Cached 14916936 kB
SwapCached 0 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.1
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 2021-11-26 04:37:08
DTS_END                         2021-11-26 04:37:08.1540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56342
TRN_ID                          233988
ATT_ID                          682
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: 8859.98 23772 1063197
customer order: creation 170.62 392 20474
customer order: refuse 14.85 327 1782
order to supplier: creation 46.02 1323 5522
order to supplier: removal 3.40 103430 408
invoice (draft): creation 45.74 3501 5489
invoice (draft): removal 49.08 882 5890
invoice accept: apply 41.68 19809 5002
invoice accept: cancel 45.12 833 5414
customer reserve: creation 3769.22 200 452306
customer reserve: removal 4383.35 64 526002
realization accept: apply 82.58 187 9909
realization accept: cancel 82.18 126 9861
payment from customer: creation 57.58 115 6910
payment from customer: removal 5.70 61 684
payment to supplier: creation 49.04 134 5885
payment to supplier: removal 3.24 67 389
service: total inventory turnovers 3.73 3344 448
service: total monetary turnovers 5.89 495 707
service: aggregate perf. data 0.92 15428 110
service: refresh index statistics 0.04 324715 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-11-2021 04:38:03 to 26-11-2021 04:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3110
TEST_TIME 2 5155
TEST_TIME 3 6248
TEST_TIME 4 6263
TEST_TIME 5 5728
TEST_TIME 6 6019
TEST_TIME 7 6215
TEST_TIME 8 6264
TEST_TIME 9 6123
TEST_TIME 10 6468
TEST_TIME 11 6554
TEST_TIME 12 6486
TEST_TIME 13 6843
TEST_TIME 14 7348
TEST_TIME 15 7695
TEST_TIME 16 7788
TEST_TIME 17 8176
TEST_TIME 18 8077
TEST_TIME 19 8376
TEST_TIME 20 8554
TEST_TIME 21 8908
TEST_TIME 22 9573
TEST_TIME 23 9477
TEST_TIME 24 9573
TEST_TIME 25 9842
TEST_TIME 26 10621
TEST_TIME 27 10666
TEST_TIME 28 10388
TEST_TIME 29 10290
TEST_TIME 30 10141
TEST_TIME 31 9983
TEST_TIME 32 10226
TEST_TIME 33 10017
TEST_TIME 34 9972
TEST_TIME 35 9930
TEST_TIME 36 9836
TEST_TIME 37 9704
TEST_TIME 38 9791
TEST_TIME 39 9647
TEST_TIME 40 9633
TEST_TIME 41 9674
TEST_TIME 42 9700
TEST_TIME 43 9690
TEST_TIME 44 9700
TEST_TIME 45 9623
TEST_TIME 46 9773
TEST_TIME 47 9758
TEST_TIME 48 9777
TEST_TIME 49 9848
TEST_TIME 50 9795
TEST_TIME 51 9833
TEST_TIME 52 9739
TEST_TIME 53 9617
TEST_TIME 54 9550
TEST_TIME 55 9468
TEST_TIME 56 9366
TEST_TIME 57 9280
TEST_TIME 58 9246
TEST_TIME 59 9320
TEST_TIME 60 9445
TEST_TIME 61 9376
TEST_TIME 62 9335
TEST_TIME 63 9360
TEST_TIME 64 9278
TEST_TIME 65 9373
TEST_TIME 66 9300
TEST_TIME 67 9469
TEST_TIME 68 9530
TEST_TIME 69 9501
TEST_TIME 70 9573
TEST_TIME 71 9577
TEST_TIME 72 9468
TEST_TIME 73 9370
TEST_TIME 74 9331
TEST_TIME 75 9260
TEST_TIME 76 9253
TEST_TIME 77 9218
TEST_TIME 78 9158
TEST_TIME 79 9148
TEST_TIME 80 9236
TEST_TIME 81 9212
TEST_TIME 82 9229
TEST_TIME 83 9267
TEST_TIME 84 9255
TEST_TIME 85 9283
TEST_TIME 86 9312
TEST_TIME 87 9321
TEST_TIME 88 9317
TEST_TIME 89 9329
TEST_TIME 90 9385
TEST_TIME 91 9301
TEST_TIME 92 9240
TEST_TIME 93 9184
TEST_TIME 94 9106
TEST_TIME 95 9079
TEST_TIME 96 9063
TEST_TIME 97 9046
TEST_TIME 98 9097
TEST_TIME 99 9075
TEST_TIME 100 9070
TEST_TIME 101 9082
TEST_TIME 102 9154
TEST_TIME 103 9159
TEST_TIME 104 9131
TEST_TIME 105 9121
TEST_TIME 106 9111
TEST_TIME 107 9126
TEST_TIME 108 9143
TEST_TIME 109 9174
TEST_TIME 110 9136
TEST_TIME 111 9078
TEST_TIME 112 9049
TEST_TIME 113 9010
TEST_TIME 114 8960
TEST_TIME 115 8919
TEST_TIME 116 8933
TEST_TIME 117 8908
TEST_TIME 118 8938
TEST_TIME 119 8931
TEST_TIME 120 8861

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-11-2021 04:38:07 to 26-11-2021 04:38:10.

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 1483498 1483498 0 0.00 0 22267 7 0 0
doc_list_biud 1483498 1483498 0 0.00 0 81261 29 0 0
sp_add_invoice_to_stock 5105 5002 103 2.01 39 252106 19810 0 103
sp_cancel_adding_invoice 5414 5414 0 0.00 5 82416 834 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 19395 328 0 0
sp_cancel_customer_reserve 526002 526002 0 0.00 1 44895 64 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 1391 61 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 2724 67 0 0
sp_cancel_supplier_invoice 5903 5890 13 0.22 2 81277 883 0 13
sp_cancel_supplier_order 408 408 0 0.00 6 1095708 103430 0 0
sp_cancel_write_off 9861 9861 0 0.00 1 11229 126 0 0
sp_client_order 20474 20474 0 0.00 4 41633 392 0 0
sp_customer_reserve 452306 452306 0 0.00 0 94490 201 0 0
sp_fill_shopping_cart 31467 31467 0 0.00 1 17108 41 0 0
sp_fill_shopping_cart_clo_res 452303 452303 0 0.00 0 19611 16 0 0
sp_get_clo_for_invoice 4999 4999 0 0.00 0 21797 408 0 0
sp_kill_cost_storno 16348 16348 0 0.00 0 6777 55 0 0
sp_kill_qstorno_ret_qs2qd 1068163 1068163 0 0.00 0 81259 35 0 0
sp_kill_qty_storno 564265 564265 0 0.00 0 81261 75 0 0
sp_lock_selected_doc 20653 20653 0 0.00 0 9694 7 0 0
sp_make_cost_storno 27703 27703 0 0.00 0 14248 59 0 0
sp_multiply_rows_for_pdistr 27703 27703 0 0.00 0 8252 32 0 0
sp_multiply_rows_for_qdistr 20474 20474 0 0.00 0 18015 205 0 0
sp_pay_from_customer 6910 6910 0 0.00 4 12315 115 0 0
sp_pay_to_supplier 5885 5885 0 0.00 4 15316 135 0 0
sp_payment_common 12795 12795 0 0.00 1 8621 22 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 19386 297 0 0
sp_qd_handle_on_invoice_upd_sts 10413 10413 0 0.00 0 28916 324 0 0
sp_qd_handle_on_reserve_upd_sts 9909 9909 0 0.00 0 12417 60 0 0
sp_reserve_write_off 9909 9909 0 0.00 7 16378 187 0 0
sp_supplier_invoice 5489 5489 0 0.00 6 126871 3501 0 0
sp_supplier_order 5522 5522 0 0.00 5 27973 1324 0 0
srv_aggregate_perf_data 111 110 1 0.90 2714 166339 15428 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2445 165883 48035 0 0
srv_find_qd_qs_mism 1016955 1016955 0 0.00 0 22266 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 23 94831 3344 0 0
srv_make_money_saldo 707 707 0 0.00 1 21082 496 0 0
srv_recalc_idx_stat 5 5 0 0.00 170327 503546 324715 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 25082 173 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 6175 93 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 6142 14 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 3451 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 3964 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 842 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 904 17 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 19167 1379 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 1833 9 0 0
t$perf-norm:sp_client_order 20474 20474 0 0.00 0 20840 21 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 4455 20 0 0
t$perf-norm:sp_pay_from_customer 6910 6910 0 0.00 0 4077 8 0 0
t$perf-norm:sp_pay_to_supplier 5885 5885 0 0.00 0 10332 16 0 0
t$perf-norm:sp_reserve_write_off 9909 9909 0 0.00 0 13489 14 0 0
t$perf-norm:sp_supplier_invoice 5489 5489 0 0.00 0 8060 21 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 6104 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 172 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 425 11 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 1431 11 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 385 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10204 9998 206 2.01 0 5400 17 0 206
v_all_customers 37114 37114 0 0.00 0 16 1 0 0
v_all_suppliers 30664 30664 0 0.00 0 101 1 0 0
v_all_wares 40948 40948 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 4716 17 0 0
v_cancel_client_order 3564 3564 0 0.00 0 2100 6 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 990 5 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 563 4 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 2700 8 0 0
v_cancel_supplier_invoice 794 768 26 3.27 0 1166 14 0 26
v_cancel_supplier_order 816 816 0 0.00 0 5189 13 0 0
v_cancel_write_off 1954 1954 0 0.00 0 9287 18 0 0
v_max_id_clo_ord 5521 5521 0 0.00 0 6461 3 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4039 15 0 0
v_max_id_ord_sup 5472 5472 0 0.00 0 5192 5 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 588 2 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 3836 5 0 0
v_min_id_clo_ord 5521 5521 0 0.00 0 4652 6 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 3080 9 0 0
v_min_id_ord_sup 5472 5472 0 0.00 0 13224 12 0 0
v_min_non_paid_invoice 5885 5885 0 0.00 0 8617 16 0 0
v_min_non_paid_realizn 6910 6910 0 0.00 0 4707 14 0 0
v_our_firm 10146 10146 0 0.00 0 55 1 0 0
v_reserve_write_off 19818 19818 0 0.00 0 5820 6 0 0
x_lock_depdocs_on_canc_invoice 10920 10920 0 0.00 0 25251 123 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 25797 350 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 2 27965 1246 0 0
x_make_qstorno_1200_2000 5489 5489 0 0.00 3 126845 3342 0 0
x_make_qstorno_2100_3300 452306 452306 0 0.00 0 94487 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-11-2021 04:38:11 to 26-11-2021 04:38:15.

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:11 1012 8192000000 8963925712 9029660672 7194784 0.087 3 2 4 0 7356992 90720 71488
02:08:15 25972 8192000000 9732194240 10034544640 802240480 8.919 100 81 162 0 828536656 7428768 18867408
02:09:46 13968 8192000000 9775447408 10083201024 839689280 9.297 100 91 182 1 873988960 12645904 21653776
02:11:05 15877 8192000000 9808052560 10127400960 870388400 9.604 100 85 170 0 908863424 19282912 19192112
02:12:27 15290 8192000000 9864476912 10196017152 900432464 9.903 100 72 144 0 962482464 46076608 15973392
02:13:45 8942 8192000000 9913777392 10257620992 939166176 10.285 100 77 155 1 1020117056 64760256 16190624
02:14:58 8436 8192000000 9959743168 10312667136 979354176 10.678 100 74 149 0 1063826464 69115568 15356720
02:16:10 3956 8192000000 9991410800 10359123968 1013557040 11.010 100 71 142 0 1090978704 63095600 14326064
02:17:17 2491 8192000000 10010314352 10376736768 1032940000 11.197 100 85 170 2 1115045328 64975248 17130080
02:18:22 2216 8192000000 10051725840 10419961856 1050699584 11.367 100 90 179 0 1143269488 74116160 18453744
02:19:25 4394 8192000000 10008981664 10395586560 1052518928 11.385 100 45 89 0 1118692208 57034288 9138992
02:20:31 12479 8192000000 9994257232 10405613568 1065623776 11.510 100 33 66 0 1104389968 31926192 6840000
02:21:48 3616 8192000000 9993210288 10387267584 1073053184 11.581 100 50 99 4 1101619952 18860224 9706544
02:22:54 25904 8192000000 9987586592 10396246016 1074683200 11.597 100 28 56 0 1100922928 20426224 5813504
02:24:21 5884 8192000000 10010273328 10407596032 1078121744 11.630 100 27 55 0 1113832464 30043648 5667072
02:25:30 4689 8192000000 10024993728 10418786304 1085386304 11.699 100 43 86 1 1124051680 28973776 9691600
02:26:37 9330 8192000000 10012453520 10420342784 1084941744 11.695 100 26 52 0 1101513456 11105536 5466176
02:27:50 38387 8192000000 9976837008 10387664896 1015425792 11.028 94 16 32 0 1037421648 18197728 3798128
02:29:32 7173 8192000000 9941707104 10333323264 988739104 10.769 95 34 66 0 1011556384 15487024 7330256
02:30:39 7332 8192000000 9941272752 10327265280 999510448 10.874 98 23 45 0 1034835728 30246672 5078608
02:31:49 60594 8192000000 9913127808 10300878848 990511776 10.786 97 35 70 0 1009087616 10373616 8202224
02:33:50 6089 8192000000 9951793248 10339622912 1008326016 10.959 97 39 75 2 1045691744 28657568 8708160
02:34:58 7288 8192000000 9973101632 10370605056 1049048832 11.352 100 38 76 3 1077450608 20396688 8005088
02:36:07 5144 8192000000 9985048224 10375360512 1066537376 11.519 100 44 88 0 1093626864 16998704 10090784
02:37:16 57847 8192000000 9992495120 10391248896 1081703952 11.664 100 37 73 3 1104947664 15653440 7590272
02:39:19 11243 8192000000 10068418048 10464800768 1104653424 11.882 100 57 114 1 1168793328 52666128 11473776
02:40:33 8564 8192000000 10078116320 10479034368 1120464720 12.031 100 46 92 0 1181698992 51720944 9513328
02:41:45 14890 8192000000 10097593104 10513121280 1127130592 12.094 100 46 92 0 1212561888 75783984 9647312
02:43:06 5817 8192000000 10136344976 10538946560 1139419984 12.210 100 62 121 2 1240027168 86989488 13617696
02:44:23 8253 8192000000 10155464192 10567843840 1148709840 12.297 100 46 90 4 1263261712 104956736 9595136
02:45:42 6249 8192000000 10168642432 10583773184 1161173296 12.414 100 44 84 3 1278487424 108056064 9258064
02:46:58 3823 8192000000 10221790272 10637103104 1172879296 12.524 100 68 136 1 1333493952 145025136 15589520
02:48:06 3204 8192000000 10245105392 10648068096 1179185200 12.583 100 81 159 2 1351468336 154996928 17286208
02:49:15 9111 8192000000 10274632624 10686296064 1173794544 12.532 100 65 130 1 1380835392 191765872 15274976
02:50:27 2571 8192000000 10336819728 10765877248 1180464240 12.595 100 71 140 0 1435990208 239611664 15914304
02:51:37 4437 8192000000 10325520544 10733649920 1183661024 12.624 100 72 143 0 1423860384 224024000 16175360
02:52:44 8564 8192000000 10310890448 10737819648 1184642976 12.633 100 65 127 0 1418906448 219757952 14505520
02:54:01 2444 8192000000 10296579840 10695872512 1154054224 12.348 99 84 168 0 1388993248 215314096 19624928
02:55:06 19651 8192000000 10299422224 10719838208 1143140128 12.245 98 42 84 0 1382019968 228649616 10230224
02:56:30 2789 8192000000 10347720256 10766626816 1152666160 12.335 100 55 110 1 1451925888 284506976 14752752
02:57:39 15200 8192000000 10373006224 10824994816 1154951584 12.356 100 42 84 0 1487370992 321397472 11021936
02:58:59 19404 8192000000 10293211168 10762162176 1112317920 11.954 97 33 66 0 1390768896 269444000 9006976
03:00:20 26266 8192000000 10245655328 10676854784 1120830352 12.035 99 30 58 0 1325031648 197654368 6546928
03:02:02 42286 8192000000 10165873040 10622517248 1072890448 11.580 96 18 36 1 1278979056 201628048 4460560
03:03:46 84949 8192000000 10040843648 10455805952 1075689936 11.606 97 13 26 0 1129491072 50654304 3146832
03:06:15 165882 8192000000 10123795984 10600435712 1085897680 11.704 97 27 54 0 1197676848 105873216 5905952
03:10:14 78167 8192000000 10101986240 10530529280 1134074448 12.160 100 32 62 0 1173031584 33381696 5575440
03:12:37 45296 8192000000 10105837824 10574876672 1132016560 12.140 99 22 44 0 1219550688 82974736 4559392
03:14:26 68376 8192000000 10068045728 10512445440 1120084272 12.028 98 11 22 2 1169972480 47710208 2178000
03:16:35 41923 8192000000 10060135200 10526871552 1110443584 11.937 98 14 28 1 1173725648 60457360 2824704
03:18:17 42259 8192000000 10103917328 10549092352 1140913984 12.224 100 23 46 0 1185421632 39530928 4976720
03:20:07 57551 8192000000 10116286256 10550071296 1124651248 12.071 98 33 65 1 1196306032 64081216 7573568
03:22:09 33375 8192000000 10125613888 10562560000 1131305808 12.134 98 22 44 1 1200260960 64499680 4455472
03:23:44 36057 8192000000 10115213056 10543816704 1153772976 12.345 100 30 60 1 1243118080 82663712 6681392
03:25:23 96727 8192000000 10097685440 10589556736 1134972496 12.168 98 21 42 0 1221784960 82131424 4681040
03:28:00 20126 8192000000 10103067776 10556235776 1140399744 12.219 99 34 67 1 1200984080 53024272 7560064
03:29:21 110026 8192000000 10072285248 10505302016 1107496368 11.909 96 32 64 1 1143108336 29220672 6391296
03:32:19 66162 8192000000 10124369680 10565799936 1128724976 12.109 98 21 42 0 1207026496 74124496 4177024
03:34:28 70661 8192000000 10160227040 10625220608 1126218928 12.086 98 12 24 0 1227284064 98648496 2416640
03:36:45 47736 8192000000 10091384768 10659450880 1115004480 11.980 98 11 22 0 1272928256 155213920 2709856
03:38:33 39238 8192000000 10092925648 10534023168 1114618736 11.976 98 26 52 1 1179101056 58570512 5911808
03:40:13 79371 8192000000 10143852128 10579894272 1122454560 12.050 98 18 36 0 1234206464 107583920 4167984
03:42:33 76766 8192000000 10154816752 10607116288 1107925168 11.913 97 10 19 1 1248105824 138001072 2179584
03:44:49 34542 8192000000 10102660368 10574946304 1098958352 11.828 97 16 32 0 1170153712 68124624 3070736
03:46:25 12403 8192000000 10054760992 10477285376 1091383104 11.756 99 49 98 1 1137556176 35555360 10617712
03:47:39 83294 8192000000 10065472800 10540363776 1108021040 11.914 98 26 52 1 1198666480 85352048 5293392
03:50:06 60952 8192000000 10061121104 10500653056 1111213104 11.944 98 29 58 2 1142078304 24951840 5913360
03:52:11 63644 8192000000 10090220832 10541486080 1118750464 12.015 99 12 24 1 1180762992 59640976 2371552
03:54:14 35603 8192000000 10082407120 10511462400 1134327680 12.162 100 22 42 1 1179615280 40480416 4807184
03:55:52 103862 8192000000 10108324432 10585522176 1117794112 12.006 98 10 20 0 1194450368 74240320 2415936
03:59:27 59136 8192000000 10066793120 10577809408 1103890400 11.875 97 23 46 0 1188150080 79122864 5136816
04:01:29 32196 8192000000 10087011328 10535780352 1093878400 11.780 97 37 74 1 1158852624 57365440 7608784
04:03:08 73714 8192000000 10047150304 10511564800 1090117888 11.744 97 7 14 0 1158115616 66608880 1388848
04:05:24 29315 8192000000 10076233824 10497159168 1115901296 11.988 99 36 72 1 1163512176 40747040 6863840
04:06:58 151103 8192000000 10056840816 10526711808 1110574816 11.938 98 22 44 0 1149649536 34741120 4333600
04:10:41 65009 8192000000 10098270368 10547843072 1143470848 12.248 100 12 24 0 1192259312 46373488 2414976
04:12:47 49066 8192000000 10104309920 10586894336 1140036928 12.216 99 8 16 0 1201245856 59395920 1813008
04:14:46 82803 8192000000 10084085360 10545827840 1135019888 12.169 99 15 30 1 1178225600 40483616 2722096
04:17:33 45763 8192000000 10108173392 10546987008 1107785728 11.911 97 25 50 0 1181810848 68209488 5815632
04:19:24 106475 8192000000 10074560592 10533457920 1128316064 12.105 99 13 26 1 1203725360 72871472 2537824
04:22:12 43122 8192000000 10098959760 10533470208 1137351632 12.191 100 40 80 0 1195180384 50140640 7688112
04:23:57 12247 8192000000 10097504896 10516537344 1130856000 12.129 99 49 97 1 1172822592 32606320 9360272
04:25:14 71922 8192000000 10095224640 10558521344 1141260400 12.227 99 17 34 2 1189296736 44672944 3363392
04:27:28 96565 8192000000 10054720688 10529476608 1092029696 11.762 96 14 28 0 1133189856 37907888 3252272
04:30:20 60728 8192000000 10052672656 10520252416 1124335680 12.068 100 7 14 0 1161585616 35491472 1758464
04:32:21 81211 8192000000 10088415872 10562686976 1110633072 11.938 98 26 53 2 1168000240 51408304 5958864
04:34:45 65345 8192000000 10087559120 10527244288 1088095600 11.725 97 13 26 1 1147643392 56504176 3043616
04:36:50 20848 8192000000 9090165440 9327390720 242619216 2.876 22 16 28 2 253230960 7779776 2831968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-11-2021 04:38:17 to 26-11-2021 04:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 103 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-11-2021 04:38:32 to 26-11-2021 04:38:34.

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          234279
MON$OLDEST_ACTIVE               234280
MON$OLDEST_SNAPSHOT             234280
MON$NEXT_TRANSACTION            234280
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                       2033312
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:365: 
:366: mscs-test-161.budzdorov.ru    Fri Nov 26 04:37:13 2021
:367:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:368: 
--- end of diff output ---

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

26.11.21 04:38:35. 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
2021-11-26 04:38:38 No severe PSQL-related problems occured
26.11.21 04:38:39. End of report.