Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15669464 kB
MemAvailable 30693728 kB
Buffers 20 kB
Cached 16006720 kB
SwapCached 3848 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-10-11 04:37:09
DTS_END                         2021-10-11 04:37:09.1230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47014
TRN_ID                          235794
ATT_ID                          651
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: 9078.45 21711 1089414
customer order: creation 171.58 430 20589
customer order: refuse 14.76 312 1771
order to supplier: creation 46.53 1568 5584
order to supplier: removal 3.41 102264 409
invoice (draft): creation 46.64 3383 5597
invoice (draft): removal 50.62 903 6074
invoice accept: apply 41.77 17980 5012
invoice accept: cancel 45.95 792 5514
customer reserve: creation 3856.16 178 462739
customer reserve: removal 4507.40 61 540888
realization accept: apply 83.16 198 9979
realization accept: cancel 80.12 120 9614
payment from customer: creation 60.13 124 7215
payment from customer: removal 6.29 105 755
payment to supplier: creation 49.36 117 5923
payment to supplier: removal 3.52 96 422
service: total inventory turnovers 3.84 3669 461
service: total monetary turnovers 6.35 543 762
service: aggregate perf. data 0.84 16782 101
service: refresh index statistics 0.04 284587 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2021 04:39:30 to 11-10-2021 04:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7001
TEST_TIME 2 5673
TEST_TIME 3 5758
TEST_TIME 4 5638
TEST_TIME 5 5837
TEST_TIME 6 5419
TEST_TIME 7 5426
TEST_TIME 8 5541
TEST_TIME 9 5555
TEST_TIME 10 5685
TEST_TIME 11 5907
TEST_TIME 12 6443
TEST_TIME 13 6595
TEST_TIME 14 6889
TEST_TIME 15 7405
TEST_TIME 16 7999
TEST_TIME 17 8019
TEST_TIME 18 8085
TEST_TIME 19 8819
TEST_TIME 20 8887
TEST_TIME 21 9047
TEST_TIME 22 9237
TEST_TIME 23 9323
TEST_TIME 24 9509
TEST_TIME 25 10108
TEST_TIME 26 10573
TEST_TIME 27 10502
TEST_TIME 28 10238
TEST_TIME 29 10625
TEST_TIME 30 10784
TEST_TIME 31 10742
TEST_TIME 32 10681
TEST_TIME 33 10481
TEST_TIME 34 10383
TEST_TIME 35 10220
TEST_TIME 36 10366
TEST_TIME 37 10335
TEST_TIME 38 10313
TEST_TIME 39 10324
TEST_TIME 40 10251
TEST_TIME 41 10085
TEST_TIME 42 10071
TEST_TIME 43 9946
TEST_TIME 44 10156
TEST_TIME 45 10192
TEST_TIME 46 10188
TEST_TIME 47 9984
TEST_TIME 48 9935
TEST_TIME 49 9838
TEST_TIME 50 9703
TEST_TIME 51 9714
TEST_TIME 52 9754
TEST_TIME 53 9719
TEST_TIME 54 9739
TEST_TIME 55 9732
TEST_TIME 56 9730
TEST_TIME 57 9639
TEST_TIME 58 9539
TEST_TIME 59 9535
TEST_TIME 60 9449
TEST_TIME 61 9605
TEST_TIME 62 9509
TEST_TIME 63 9604
TEST_TIME 64 9621
TEST_TIME 65 9587
TEST_TIME 66 9582
TEST_TIME 67 9662
TEST_TIME 68 9733
TEST_TIME 69 9726
TEST_TIME 70 9678
TEST_TIME 71 9782
TEST_TIME 72 9751
TEST_TIME 73 9744
TEST_TIME 74 9786
TEST_TIME 75 9813
TEST_TIME 76 9699
TEST_TIME 77 9671
TEST_TIME 78 9602
TEST_TIME 79 9593
TEST_TIME 80 9547
TEST_TIME 81 9486
TEST_TIME 82 9442
TEST_TIME 83 9349
TEST_TIME 84 9419
TEST_TIME 85 9372
TEST_TIME 86 9370
TEST_TIME 87 9335
TEST_TIME 88 9345
TEST_TIME 89 9339
TEST_TIME 90 9331
TEST_TIME 91 9323
TEST_TIME 92 9298
TEST_TIME 93 9336
TEST_TIME 94 9562
TEST_TIME 95 9548
TEST_TIME 96 9554
TEST_TIME 97 9519
TEST_TIME 98 9434
TEST_TIME 99 9439
TEST_TIME 100 9390
TEST_TIME 101 9317
TEST_TIME 102 9279
TEST_TIME 103 9248
TEST_TIME 104 9188
TEST_TIME 105 9146
TEST_TIME 106 9124
TEST_TIME 107 9091
TEST_TIME 108 9167
TEST_TIME 109 9129
TEST_TIME 110 9110
TEST_TIME 111 9088
TEST_TIME 112 9137
TEST_TIME 113 9151
TEST_TIME 114 9186
TEST_TIME 115 9163
TEST_TIME 116 9197
TEST_TIME 117 9245
TEST_TIME 118 9175
TEST_TIME 119 9137
TEST_TIME 120 9082

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2021 04:39:34 to 11-10-2021 04:39:37.

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 1517498 1517498 0 0.00 0 15505 6 0 0
doc_list_biud 1517491 1517491 0 0.00 0 38622 28 0 0
sp_add_invoice_to_stock 5119 5012 107 2.09 30 208573 17981 0 107
sp_cancel_adding_invoice 5514 5514 0 0.00 5 64131 793 0 0
sp_cancel_client_order 1771 1771 0 0.00 5 10937 313 0 0
sp_cancel_customer_reserve 540888 540888 0 0.00 1 38627 61 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 3 15600 105 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 4251 97 0 0
sp_cancel_supplier_invoice 6082 6074 8 0.13 2 20892 904 0 8
sp_cancel_supplier_order 409 409 0 0.00 16 922531 102265 0 0
sp_cancel_write_off 9614 9614 0 0.00 1 12477 121 0 0
sp_client_order 20589 20589 0 0.00 4 24072 430 0 0
sp_customer_reserve 462739 462739 0 0.00 0 91330 179 0 0
sp_fill_shopping_cart 31761 31761 0 0.00 1 15214 43 0 0
sp_fill_shopping_cart_clo_res 462727 462727 0 0.00 0 17148 17 0 0
sp_get_clo_for_invoice 5000 5000 0 0.00 0 21433 372 0 0
sp_kill_cost_storno 16305 16305 0 0.00 0 11266 60 0 0
sp_kill_qstorno_ret_qs2qd 1097873 1097873 0 0.00 0 38616 33 0 0
sp_kill_qty_storno 579245 579245 0 0.00 0 38622 72 0 0
sp_lock_selected_doc 20970 20970 0 0.00 0 11381 7 0 0
sp_make_cost_storno 28117 28117 0 0.00 0 13444 63 0 0
sp_multiply_rows_for_pdistr 28113 28113 0 0.00 0 13310 33 0 0
sp_multiply_rows_for_qdistr 20589 20589 0 0.00 0 24056 229 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 13317 125 0 0
sp_pay_to_supplier 5923 5923 0 0.00 5 13472 118 0 0
sp_payment_common 13138 13138 0 0.00 1 7832 26 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 7619 279 0 0
sp_qd_handle_on_invoice_upd_sts 10511 10511 0 0.00 0 14228 319 0 0
sp_qd_handle_on_reserve_upd_sts 9978 9978 0 0.00 0 13256 55 0 0
sp_reserve_write_off 9979 9979 0 0.00 7 17985 199 0 0
sp_supplier_invoice 5597 5597 0 0.00 9 79315 3384 0 0
sp_supplier_order 5584 5584 0 0.00 5 25236 1569 0 0
srv_aggregate_perf_data 102 101 1 0.98 2783 176952 16783 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1265 124372 46322 0 0
srv_find_qd_qs_mism 1041217 1041217 0 0.00 0 13249 5 0 0
srv_make_invnt_saldo 461 461 0 0.00 52 84781 3670 0 0
srv_make_money_saldo 762 762 0 0.00 1 18838 543 0 0
srv_recalc_idx_stat 5 5 0 0.00 159977 415319 284588 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 5012 5012 0 0.00 0 11798 164 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 13692 119 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 2383 10 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 5262 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 324 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 2909 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1235 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 24708 1504 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 2018 7 0 0
t$perf-norm:sp_client_order 20589 20589 0 0.00 0 12819 18 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 2850 8 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 10498 11 0 0
t$perf-norm:sp_pay_to_supplier 5923 5923 0 0.00 0 13434 12 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 13309 13 0 0
t$perf-norm:sp_supplier_invoice 5597 5597 0 0.00 0 11507 15 0 0
t$perf-norm:sp_supplier_order 5584 5584 0 0.00 0 12926 14 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 870 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 209 5 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 7162 21 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 1400 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10208 9994 214 2.09 0 12160 23 0 214
v_all_customers 37676 37676 0 0.00 0 20 1 0 0
v_all_suppliers 31074 31074 0 0.00 0 123 1 0 0
v_all_wares 41176 41176 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 837 7 0 0
v_cancel_client_order 3542 3542 0 0.00 0 1820 4 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 1517 6 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 3275 10 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 4241 14 0 0
v_cancel_supplier_invoice 840 824 16 1.90 0 1875 22 0 16
v_cancel_supplier_order 818 818 0 0.00 0 2653 8 0 0
v_cancel_write_off 2104 2104 0 0.00 0 4238 18 0 0
v_max_id_clo_ord 5582 5582 0 0.00 0 237 1 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 14986 31 0 0
v_max_id_ord_sup 5590 5590 0 0.00 0 9724 7 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 324 2 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 4700 6 0 0
v_min_id_clo_ord 5582 5582 0 0.00 0 3148 6 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 10321 33 0 0
v_min_id_ord_sup 5590 5590 0 0.00 0 5714 8 0 0
v_min_non_paid_invoice 5923 5923 0 0.00 0 7239 15 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 7828 22 0 0
v_our_firm 10302 10302 0 0.00 0 19 1 0 0
v_reserve_write_off 19956 19956 0 0.00 0 11597 6 0 0
x_lock_depdocs_on_canc_invoice 11176 11176 0 0.00 0 31390 105 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 11152 314 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 2 25230 1491 0 0
x_make_qstorno_1200_2000 5597 5597 0 0.00 4 71876 3223 0 0
x_make_qstorno_2100_3300 462739 462739 0 0.00 0 91241 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2021 04:39:38 to 11-10-2021 04:39:42.

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:12 11 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:13 37905 8192000000 9725060640 10025697280 804645472 8.943 100 87 174 0 833524640 7572720 21306448
02:09:56 22659 8192000000 9783463120 10094055424 852438816 9.425 100 87 174 0 885924448 12982480 20503152
02:11:24 14390 8192000000 9825525952 10148892672 887399424 9.773 100 78 156 0 928469552 23659936 17410192
02:12:39 13406 8192000000 9895035808 10232471552 939188992 10.285 100 76 152 0 1005967056 50950048 15828016
02:13:58 12159 8192000000 9962864768 10310029312 973062912 10.617 100 89 176 1 1063205856 71816512 18326432
02:15:14 3859 8192000000 9968332416 10323173376 999978000 10.878 100 83 166 0 1086101568 69667552 16456016
02:16:20 2270 8192000000 10010685296 10371764224 1027271152 11.142 100 89 177 3 1116730512 71952208 17507152
02:17:25 1974 8192000000 10024516816 10392588288 1049467024 11.356 100 93 188 1 1140814176 72438336 18908816
02:18:29 1704 8192000000 10047649488 10420957184 1065137888 11.506 100 92 182 0 1160692480 77357936 18196656
02:19:32 4691 8192000000 10014609488 10410651648 1071870272 11.570 100 51 102 2 1125891040 43525792 10494976
02:20:37 5484 8192000000 10014655152 10409865216 1080109184 11.649 100 48 93 2 1125528896 35693024 9726688
02:21:43 43891 8192000000 10026032768 10422202368 1089179936 11.735 100 61 123 2 1136175488 34169424 12826128
02:23:27 4949 8192000000 10003199568 10408472576 1084718352 11.692 100 38 75 0 1118852352 25804016 8329984
02:24:33 9516 8192000000 10006713744 10418966528 1092145776 11.763 100 32 64 2 1129499200 30684960 6668464
02:25:43 7617 8192000000 10015663328 10425339904 1095306768 11.793 100 32 63 1 1118955840 16671312 6977760
02:26:52 52739 8192000000 9999211696 10417303552 1070470432 11.557 98 28 55 1 1090630640 14542560 5617648
02:28:45 8682 8192000000 9982101616 10365091840 1014879296 11.023 94 31 61 1 1050233776 29409536 5944944
02:29:54 6546 8192000000 9974589440 10366500864 1026472640 11.134 98 48 95 4 1053547104 16471440 10603024
02:31:02 5705 8192000000 9944627728 10334027776 1015572032 11.029 98 37 74 0 1037497424 13528256 8397136
02:32:08 46934 8192000000 9943437952 10341494784 988254560 10.765 95 32 61 1 1010148528 15903904 5990064
02:33:56 4599 8192000000 9965442032 10350821376 1041370736 11.278 100 67 134 0 1078015232 21689008 14955488
02:35:03 7011 8192000000 9985537952 10372308992 1050370240 11.364 100 39 77 1 1085983328 26908672 8704416
02:36:12 6443 8192000000 9978001392 10376585216 1058103376 11.438 100 20 39 2 1089704208 27068048 4532784
02:37:19 53931 8192000000 9992013536 10389794816 1076217616 11.611 100 44 87 0 1111346592 25613232 9515744
02:39:20 20372 8192000000 10043931792 10445111296 1095261824 11.793 100 46 90 0 1147335184 42597568 9475792
02:40:45 8564 8192000000 10061829216 10471178240 1112310864 11.954 100 40 80 0 1184658960 64038592 8309504
02:41:55 29170 8192000000 10113895616 10529964032 1114865392 11.978 99 53 106 1 1214604304 88157072 11581840
02:43:44 10767 8192000000 10158884624 10564825088 1148051920 12.291 100 70 134 3 1261606096 97341632 16212544
02:45:00 25260 8192000000 10169024000 10593804288 1161706016 12.419 100 41 81 2 1288157968 117100864 9351088
02:46:28 3143 8192000000 10231178496 10640834560 1166804160 12.467 100 72 144 0 1341241568 158597728 15839680
02:47:37 7609 8192000000 10270068528 10688036864 1175476144 12.548 100 58 116 2 1377536608 188758752 13301712
02:48:50 4848 8192000000 10302403328 10732679168 1183804208 12.626 100 78 156 0 1420597952 219050752 17742992
02:49:58 2928 8192000000 10334180448 10750783488 1185133888 12.638 100 64 128 1 1436551728 236706304 14711536
02:51:05 1265 8192000000 10357761728 10761437184 1170100640 12.498 99 76 152 0 1453995424 266432192 17462592
02:52:17 9073 8192000000 10379853856 10801168384 1157963632 12.384 98 61 119 1 1486344240 314482784 13897824
02:53:34 2687 8192000000 10367598592 10827202560 1175750128 12.551 100 61 120 0 1471796864 282187184 13859552
02:54:39 2260 8192000000 10333159104 10754945024 1169156592 12.489 100 53 107 1 1435116928 253244128 12716208
02:55:50 3892 8192000000 10359300080 10792161280 1155008288 12.356 100 61 122 0 1454576512 285820064 13748160
02:56:58 20252 8192000000 10285762544 10720337920 1111779056 11.949 97 24 48 2 1373976704 255683264 6514384
02:58:25 16288 8192000000 10251930880 10708606976 1118115936 12.009 100 25 50 2 1381822064 257250752 6455376
02:59:44 34415 8192000000 10255921376 10727874560 1075410512 11.604 95 23 45 1 1319516192 238092608 6013072
03:01:21 27726 8192000000 10255387664 10701393920 1093912608 11.780 98 24 48 1 1330012800 230005152 6095040
03:02:50 5629 8192000000 10218675344 10661699584 1124911456 12.073 100 57 114 0 1302524080 163676528 13936096
03:04:07 57831 8192000000 10197550496 10640404480 1117401840 12.002 99 47 94 0 1271106688 143277872 10426976
03:06:07 16549 8192000000 10157205600 10656620544 1119647472 12.024 100 20 40 0 1279392224 154973936 4770816
03:07:56 94903 8192000000 10065793904 10514976768 1105172480 11.887 99 16 32 0 1156422720 48218592 3031648
03:10:31 36043 8192000000 10081046368 10498256896 1105353264 11.888 98 35 69 0 1143047184 30420704 7273216
03:12:19 62158 8192000000 10152669248 10575138816 1121664864 12.043 100 31 62 1 1233294112 104991744 6637504
03:14:21 46989 8192000000 10077581328 10513174528 1101409728 11.851 98 11 22 0 1141674720 38170832 2094160
03:16:56 76398 8192000000 10055298112 10475433984 1120743584 12.034 99 25 48 3 1151435328 25592352 5099392
03:19:16 20567 8192000000 10106434016 10530246656 1136300768 12.181 100 56 111 0 1189406240 41395824 11709648
03:20:50 85698 8192000000 10103984176 10634317824 1141256224 12.227 100 7 14 1 1232242368 89512592 1473552
03:23:16 109997 8192000000 10082715232 10523934720 1146492432 12.277 100 12 24 2 1189172624 40184016 2496176
03:26:10 51089 8192000000 10145414688 10570592256 1146946752 12.281 99 41 80 0 1201099072 45374400 8777920
03:28:06 49852 8192000000 10143360320 10620026880 1160177744 12.405 100 28 56 1 1267266768 100966048 6122976
03:29:58 74098 8192000000 10123914992 10571501568 1153312992 12.341 99 30 60 0 1231522144 71473680 6735472
03:32:13 13760 8192000000 10112742240 10554822656 1157269840 12.378 100 30 59 2 1201056704 37586688 6200176
03:33:29 50405 8192000000 10109898800 10570731520 1145097360 12.263 98 18 37 0 1194501872 45643056 3761456
03:35:34 32567 8192000000 10175803200 10628648960 1163110688 12.432 100 16 31 0 1268512016 101900832 3500496
03:37:09 68655 8192000000 10139876096 10604744704 1124182688 12.066 97 21 41 0 1218646064 90113312 4350064
03:39:19 66863 8192000000 10115863008 10599763968 1117417248 12.003 97 18 36 0 1174514880 53495360 3602272
03:42:09 37523 8192000000 10101415088 10544332800 1118402800 12.012 98 39 78 4 1182472592 55644672 8425120
03:43:47 85067 8192000000 10114651760 10562322432 1119118800 12.019 98 24 48 0 1228004544 103477568 5408176
03:46:26 46425 8192000000 10115828272 10559524864 1104257664 11.878 97 33 66 0 1165287456 54811568 6218224
03:48:17 48708 8192000000 10067971872 10530963456 1072580720 11.577 96 28 56 1 1156727200 78511936 5634544
03:50:08 6339 8192000000 10106555984 10525872128 1118076864 12.009 100 58 116 1 1186967216 57278784 11611568
03:51:40 67137 8192000000 10044646864 10499678208 1086368144 11.708 97 22 44 1 1145500816 54361344 4771328
03:53:47 124372 8192000000 10058763616 10512269312 1095593776 11.796 98 30 60 0 1154324000 52504720 6225504
03:56:52 38933 8192000000 10025225696 10480361472 1088647424 11.730 98 8 16 0 1131356784 40786640 1922720
03:58:46 81565 8192000000 10077393088 10550927360 1115717552 11.987 100 12 24 0 1152632832 34257328 2657952
04:01:12 85293 8192000000 10033747664 10503499776 1043398432 11.297 94 6 12 0 1109291760 64632336 1260992
04:03:37 60036 8192000000 10061642976 10497544192 1087344064 11.717 99 24 48 0 1139275504 46736768 5194672
04:05:47 46137 8192000000 10104499632 10545758208 1101256576 11.850 99 26 52 1 1147892288 41505600 5130112
04:07:34 60210 8192000000 10164552992 10599436288 1121051216 12.037 99 22 44 0 1202029680 75934640 5043824
04:09:43 43990 8192000000 10234274608 10731425792 1148498400 12.295 100 17 34 1 1344853584 192054128 4301056
04:11:29 101584 8192000000 10079993648 10530766848 1123817536 12.063 99 20 40 1 1162514160 34927920 3768704
04:14:11 100856 8192000000 10116685888 10561474560 1140438848 12.220 99 25 50 0 1196214272 50329920 5445504
04:16:53 48864 8192000000 10066428304 10538991616 1150858096 12.318 100 9 17 1 1191636400 38753504 2024800
04:18:54 34866 8192000000 10068997248 10528407552 1151792912 12.326 100 13 26 1 1178593440 24288480 2512048
04:20:32 63684 8192000000 10085839584 10556248064 1159272800 12.396 100 17 34 1 1203702704 40974160 3455744
04:22:37 83468 8192000000 10123226896 10591596544 1138576432 12.202 98 18 36 0 1191071360 48706160 3788768
04:25:03 78123 8192000000 10069060560 10541199360 1116435456 11.993 97 26 52 0 1163264352 41055536 5773360
04:27:44 52931 8192000000 10133365920 10589241344 1145377648 12.266 99 18 36 1 1213338880 63934048 4027184
04:29:38 54921 8192000000 10084075120 10557829120 1117702544 12.005 98 9 18 0 1206262320 86596352 1963424
04:31:36 49184 8192000000 10110417232 10542874624 1111481936 11.946 98 34 67 0 1188839808 70190944 7166928
04:33:32 98234 8192000000 10068067104 10550579200 1104247296 11.878 97 19 38 0 1166757632 58802432 3707904
04:36:11 61478 8192000000 9909886032 10353426432 949048736 10.382 84 11 22 1 982537952 31481728 2007488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2021 04:39:44 to 11-10-2021 04:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 107 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2021 04:39:59 to 11-10-2021 04:40:01.

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          235305
MON$OLDEST_ACTIVE               235990
MON$OLDEST_SNAPSHOT             235990
MON$NEXT_TRANSACTION            235990
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                       2064800
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2625 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2625 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2625 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2625 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Mon Oct 11 04:37:23 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

11.10.21 04:40:02. 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-10-11 04:40:05 No severe PSQL-related problems occured
11.10.21 04:40:06. End of report.