Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.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 15988452 kB
MemAvailable 30854608 kB
Buffers 20 kB
Cached 15693908 kB
SwapCached 4476 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-30 04:37:08
DTS_END                         2021-10-30 04:37:08.9920
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/34308
TRN_ID                          232893
ATT_ID                          640
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: 9011.42 25487 1081371
customer order: creation 166.91 433 20029
customer order: refuse 14.40 284 1728
order to supplier: creation 43.58 1471 5229
order to supplier: removal 3.45 104114 414
invoice (draft): creation 45.66 3700 5479
invoice (draft): removal 51.00 936 6120
invoice accept: apply 40.42 18422 4851
invoice accept: cancel 46.09 870 5531
customer reserve: creation 3795.22 180 455426
customer reserve: removal 4517.93 63 542152
realization accept: apply 81.79 193 9815
realization accept: cancel 78.03 126 9364
payment from customer: creation 58.78 125 7053
payment from customer: removal 5.55 72 666
payment to supplier: creation 47.93 131 5751
payment to supplier: removal 3.53 67 424
service: total inventory turnovers 4.06 3055 487
service: total monetary turnovers 6.24 455 749
service: aggregate perf. data 0.83 18570 99
service: refresh index statistics 0.03 356479 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3815
TEST_TIME 2 5073
TEST_TIME 3 4804
TEST_TIME 4 4859
TEST_TIME 5 4946
TEST_TIME 6 5409
TEST_TIME 7 5617
TEST_TIME 8 5801
TEST_TIME 9 6578
TEST_TIME 10 6871
TEST_TIME 11 7036
TEST_TIME 12 7296
TEST_TIME 13 7383
TEST_TIME 14 7876
TEST_TIME 15 7934
TEST_TIME 16 8333
TEST_TIME 17 9512
TEST_TIME 18 9278
TEST_TIME 19 9269
TEST_TIME 20 9348
TEST_TIME 21 9301
TEST_TIME 22 9262
TEST_TIME 23 9649
TEST_TIME 24 10117
TEST_TIME 25 10653
TEST_TIME 26 11620
TEST_TIME 27 11698
TEST_TIME 28 11335
TEST_TIME 29 10990
TEST_TIME 30 10788
TEST_TIME 31 10720
TEST_TIME 32 10760
TEST_TIME 33 10684
TEST_TIME 34 10541
TEST_TIME 35 10351
TEST_TIME 36 10291
TEST_TIME 37 10142
TEST_TIME 38 9899
TEST_TIME 39 9992
TEST_TIME 40 9966
TEST_TIME 41 9871
TEST_TIME 42 9758
TEST_TIME 43 9796
TEST_TIME 44 9791
TEST_TIME 45 9825
TEST_TIME 46 9771
TEST_TIME 47 9737
TEST_TIME 48 9770
TEST_TIME 49 9737
TEST_TIME 50 9877
TEST_TIME 51 9867
TEST_TIME 52 9835
TEST_TIME 53 9675
TEST_TIME 54 9619
TEST_TIME 55 9512
TEST_TIME 56 9489
TEST_TIME 57 9504
TEST_TIME 58 9475
TEST_TIME 59 9404
TEST_TIME 60 9373
TEST_TIME 61 9338
TEST_TIME 62 9260
TEST_TIME 63 9253
TEST_TIME 64 9317
TEST_TIME 65 9350
TEST_TIME 66 9278
TEST_TIME 67 9315
TEST_TIME 68 9263
TEST_TIME 69 9334
TEST_TIME 70 9227
TEST_TIME 71 9301
TEST_TIME 72 9423
TEST_TIME 73 9455
TEST_TIME 74 9461
TEST_TIME 75 9555
TEST_TIME 76 9512
TEST_TIME 77 9415
TEST_TIME 78 9366
TEST_TIME 79 9351
TEST_TIME 80 9336
TEST_TIME 81 9308
TEST_TIME 82 9246
TEST_TIME 83 9191
TEST_TIME 84 9147
TEST_TIME 85 9201
TEST_TIME 86 9154
TEST_TIME 87 9167
TEST_TIME 88 9157
TEST_TIME 89 9123
TEST_TIME 90 9110
TEST_TIME 91 9194
TEST_TIME 92 9221
TEST_TIME 93 9256
TEST_TIME 94 9281
TEST_TIME 95 9332
TEST_TIME 96 9347
TEST_TIME 97 9368
TEST_TIME 98 9332
TEST_TIME 99 9249
TEST_TIME 100 9263
TEST_TIME 101 9202
TEST_TIME 102 9211
TEST_TIME 103 9161
TEST_TIME 104 9160
TEST_TIME 105 9108
TEST_TIME 106 9129
TEST_TIME 107 9102
TEST_TIME 108 9120
TEST_TIME 109 9168
TEST_TIME 110 9159
TEST_TIME 111 9141
TEST_TIME 112 9129
TEST_TIME 113 9168
TEST_TIME 114 9191
TEST_TIME 115 9157
TEST_TIME 116 9187
TEST_TIME 117 9176
TEST_TIME 118 9101
TEST_TIME 119 9050
TEST_TIME 120 9013

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-10-2021 04:39:58 to 30-10-2021 04:40:01.

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 1503045 1503045 0 0.00 0 16463 7 0 0
doc_list_biud 1503045 1503045 0 0.00 0 76609 29 0 0
sp_add_invoice_to_stock 4939 4851 88 1.78 20 201142 18423 0 88
sp_cancel_adding_invoice 5531 5531 0 0.00 6 82908 871 0 0
sp_cancel_client_order 1728 1728 0 0.00 5 12160 285 0 0
sp_cancel_customer_reserve 542152 542152 0 0.00 1 62270 63 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 2842 73 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 3015 67 0 0
sp_cancel_supplier_invoice 6125 6120 5 0.08 3 76679 936 0 5
sp_cancel_supplier_order 414 414 0 0.00 7 1003456 104115 0 0
sp_cancel_write_off 9364 9364 0 0.00 1 9683 126 0 0
sp_client_order 20029 20029 0 0.00 4 67166 433 0 0
sp_customer_reserve 455426 455426 0 0.00 0 110420 181 0 0
sp_fill_shopping_cart 30731 30731 0 0.00 1 21132 52 0 0
sp_fill_shopping_cart_clo_res 455417 455417 0 0.00 0 16238 16 0 0
sp_get_clo_for_invoice 4842 4842 0 0.00 0 34101 375 0 0
sp_kill_cost_storno 15985 15985 0 0.00 0 7868 53 0 0
sp_kill_qstorno_ret_qs2qd 1100202 1100202 0 0.00 0 76606 35 0 0
sp_kill_qty_storno 579965 579965 0 0.00 0 76608 74 0 0
sp_lock_selected_doc 20338 20338 0 0.00 0 6495 4 0 0
sp_make_cost_storno 27460 27460 0 0.00 0 13366 54 0 0
sp_multiply_rows_for_pdistr 27460 27460 0 0.00 0 12834 31 0 0
sp_multiply_rows_for_qdistr 20029 20029 0 0.00 0 67159 214 0 0
sp_pay_from_customer 7053 7053 0 0.00 4 15531 125 0 0
sp_pay_to_supplier 5751 5751 0 0.00 5 10278 132 0 0
sp_payment_common 12804 12804 0 0.00 1 13886 22 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 12140 254 0 0
sp_qd_handle_on_invoice_upd_sts 10372 10372 0 0.00 0 22169 327 0 0
sp_qd_handle_on_reserve_upd_sts 9815 9815 0 0.00 0 18461 63 0 0
sp_reserve_write_off 9815 9815 0 0.00 7 18485 193 0 0
sp_supplier_invoice 5479 5479 0 0.00 6 100293 3701 0 0
sp_supplier_order 5229 5229 0 0.00 6 40780 1472 0 0
srv_aggregate_perf_data 100 99 1 1.00 2767 190752 18571 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1483 175171 47214 0 0
srv_find_qd_qs_mism 1034565 1034565 0 0.00 0 14551 6 0 0
srv_make_invnt_saldo 487 487 0 0.00 64 89465 3055 0 0
srv_make_money_saldo 749 749 0 0.00 1 14173 456 0 0
srv_recalc_idx_stat 4 4 0 0.00 177436 636784 356479 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 4851 4851 0 0.00 0 12590 139 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 2544 75 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 4343 14 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3305 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1557 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 331 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3638 20 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 12894 1338 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 541 4 0 0
t$perf-norm:sp_client_order 20029 20029 0 0.00 0 12016 17 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 2705 10 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 5377 8 0 0
t$perf-norm:sp_pay_to_supplier 5751 5751 0 0.00 0 9098 8 0 0
t$perf-norm:sp_reserve_write_off 9815 9815 0 0.00 0 5058 9 0 0
t$perf-norm:sp_supplier_invoice 5479 5479 0 0.00 0 8995 18 0 0
t$perf-norm:sp_supplier_order 5229 5229 0 0.00 0 5405 14 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 452 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 907 8 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 3194 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 9856 9680 176 1.78 0 9201 22 0 176
v_all_customers 36690 36690 0 0.00 0 17 1 0 0
v_all_suppliers 30234 30234 0 0.00 0 21 1 0 0
v_all_wares 40058 40058 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 2228 11 0 0
v_cancel_client_order 3456 3456 0 0.00 0 4642 9 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 2716 11 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 5306 14 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2766 11 0 0
v_cancel_supplier_invoice 794 784 10 1.25 0 1259 14 0 10
v_cancel_supplier_order 828 828 0 0.00 0 3034 13 0 0
v_cancel_write_off 1910 1910 0 0.00 0 5368 25 0 0
v_max_id_clo_ord 5228 5228 0 0.00 0 1787 2 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 4814 16 0 0
v_max_id_ord_sup 5474 5474 0 0.00 0 10796 11 0 0
v_max_non_paid_invoice 1336 1336 0 0.00 0 2200 4 0 0
v_max_non_paid_realizn 3687 3687 0 0.00 0 6785 6 0 0
v_min_id_clo_ord 5228 5228 0 0.00 0 12763 9 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 1702 4 0 0
v_min_id_ord_sup 5474 5474 0 0.00 0 7578 13 0 0
v_min_non_paid_invoice 5751 5751 0 0.00 0 7403 9 0 0
v_min_non_paid_realizn 7053 7053 0 0.00 0 13879 19 0 0
v_our_firm 10100 10100 0 0.00 0 16 1 0 0
v_reserve_write_off 19630 19630 0 0.00 0 8752 11 0 0
x_lock_depdocs_on_canc_invoice 11259 11259 0 0.00 0 17203 107 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 6918 274 0 0
x_make_qstorno_1000_1200 5229 5229 0 0.00 2 40595 1393 0 0
x_make_qstorno_1200_2000 5479 5479 0 0.00 3 95898 3490 0 0
x_make_qstorno_2100_3300 455426 455426 0 0.00 0 110419 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2021 04:40:02 to 30-10-2021 04:40:06.

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 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 28643 8192000000 9722696720 10024857600 804918576 8.946 100 83 166 0 832050720 7449280 19682864
02:09:47 35209 8192000000 9774877424 10085969920 842842832 9.328 100 90 180 1 877863504 13877680 21142992
02:11:28 3160 8192000000 9817509984 10139955200 885289840 9.752 100 83 166 0 928791584 24849504 18652240
02:12:40 9968 8192000000 9873664624 10209628160 924767088 10.143 100 86 171 1 988466016 45086064 18612864
02:13:52 6829 8192000000 9905737968 10255585280 961603056 10.505 100 74 148 0 1031512560 54007728 15901776
02:15:03 5618 8192000000 9966530672 10321186816 1004184608 10.919 100 84 168 0 1084765568 63066944 17514016
02:16:10 3367 8192000000 10018571872 10379702272 1028498096 11.154 100 92 181 0 1128605936 81848432 18259408
02:17:15 2287 8192000000 10030139456 10398572544 1052642160 11.386 100 86 171 1 1144985280 75200544 17142576
02:18:19 1149 8192000000 10032780544 10405969920 1067987344 11.533 100 93 186 0 1139902736 52305520 19609872
02:19:21 3943 8192000000 9990953792 10387263488 1069364688 11.546 100 38 77 1 1102963536 25271744 8327104
02:20:28 4741 8192000000 10002075536 10399191040 1078616608 11.634 100 65 132 3 1122612304 30488320 13507376
02:21:35 9654 8192000000 10007882800 10416365568 1090008256 11.743 100 29 59 0 1112102864 16031456 6063152
02:22:49 40859 8192000000 10016135840 10410672128 1091802896 11.760 100 59 117 0 1117313312 12636752 12873664
02:24:30 5836 8192000000 10029502144 10424242176 1099675744 11.835 100 47 91 1 1132481360 23185072 9620544
02:25:39 3231 8192000000 10022821632 10423001088 1104360192 11.879 100 42 81 2 1133461488 20041904 9059392
02:26:44 54756 8192000000 10022627008 10427883520 1092655152 11.768 99 37 74 0 1125510720 23291808 9563760
02:28:40 3912 8192000000 10003880304 10400112640 1062567728 11.481 99 41 80 2 1096085744 25379776 8138240
02:29:45 6711 8192000000 9941003232 10334547968 1007985968 10.956 97 49 97 1 1039868224 20263136 11619120
02:30:52 6273 8192000000 9946323008 10319495168 1000387024 10.882 96 48 92 2 1029686672 18283664 11015984
02:32:02 30349 8192000000 9920149584 10304221184 912238128 10.019 89 25 50 0 931788544 14045088 5505328
02:33:37 5719 8192000000 9959158112 10349236224 1038496640 11.250 100 60 119 1 1074564000 22898208 13169152
02:34:45 5844 8192000000 9974946144 10357633024 1052894832 11.388 100 32 64 0 1095134880 35141712 7098336
02:35:51 69786 8192000000 9978084032 10386952192 1065629184 11.510 100 32 63 1 1092096016 19618000 6848832
02:38:11 21251 8192000000 10035049056 10430107648 1096982688 11.809 100 49 95 2 1137777056 31227408 9566960
02:39:35 35736 8192000000 10097419008 10499981312 1116690864 11.996 100 45 89 0 1205163008 79533824 8938320
02:41:18 22774 8192000000 10148814160 10552819712 1131434656 12.135 100 50 100 1 1243178720 100764144 10979920
02:42:45 9501 8192000000 10186495312 10593398784 1141718448 12.232 100 45 90 3 1292356784 140649920 9988416
02:44:02 13517 8192000000 10215195232 10645889024 1142417008 12.238 100 41 82 0 1326853856 174446720 9990128
02:45:31 6177 8192000000 10226350016 10627633152 1140621808 12.221 100 66 128 1 1326799696 171044208 15133680
02:46:43 6202 8192000000 10202869456 10608754688 1146962240 12.281 99 58 116 0 1311417936 151674384 12781312
02:47:55 12740 8192000000 10223888976 10637352960 1155307216 12.359 99 57 113 3 1313546288 145149840 13089232
02:49:14 5212 8192000000 10290599520 10702180352 1181935744 12.608 100 71 142 1 1391639728 193505072 16198912
02:50:24 1479 8192000000 10330602736 10731266048 1168929872 12.487 100 91 182 0 1431617360 241828752 20858736
02:51:30 2259 8192000000 10306936384 10729873408 1150596544 12.315 99 65 130 1 1414626576 248402496 15627536
02:52:38 6541 8192000000 10322932976 10734850048 1154697744 12.354 100 61 122 3 1422125632 253262640 14165248
02:53:52 7256 8192000000 10356834208 10767872000 1148231872 12.293 99 66 132 2 1451726256 288181360 15313024
02:55:05 11203 8192000000 10238009776 10659246080 1159997376 12.403 100 40 78 2 1339084224 169274400 9812448
02:56:20 10833 8192000000 10291608384 10731114496 1141483024 12.229 98 42 84 0 1371632576 219547072 10602480
02:57:35 12777 8192000000 10332075920 10757922816 1155151920 12.358 100 44 87 1 1427691968 262044032 10496016
02:59:04 18838 8192000000 10367555008 10802409472 1140438960 12.220 100 31 62 1 1464829872 316235728 8155184
03:00:44 14846 8192000000 10352925136 10816761856 1120561472 12.032 100 25 50 0 1468107232 340729696 6816064
03:02:00 26214 8192000000 10229997664 10686488576 1116267264 11.992 99 28 57 0 1373500896 250359632 6874000
03:03:33 74979 8192000000 10070174592 10535899136 1075999424 11.609 96 10 20 0 1143767200 65363280 2404496
03:05:50 56678 8192000000 10046430560 10454458368 1095184320 11.792 98 54 106 1 1124447888 17818224 11445344
03:07:47 65443 8192000000 10129147184 10575409152 1127272320 12.096 100 32 62 1 1223267328 89553264 6441744
03:10:09 120425 8192000000 10041206288 10480599040 1067546912 11.529 95 12 24 0 1096965296 27458896 1959488
03:14:34 34868 8192000000 10079399280 10527158272 1111797408 11.949 99 14 28 1 1171997120 56932000 3267712
03:17:31 44686 8192000000 10047889616 10488709120 1125255472 12.077 100 15 30 2 1147679376 19396992 3026912
03:19:16 64278 8192000000 10101351840 10584178688 1140056176 12.216 100 22 43 1 1215596656 71015024 4525456
03:21:24 14995 8192000000 10091652416 10519543808 1143386560 12.247 100 43 81 2 1178361584 26959824 8015200
03:22:41 44025 8192000000 10074451936 10505150464 1104437840 11.880 96 32 64 2 1133637376 23014464 6185072
03:24:25 48290 8192000000 10096300416 10531991552 1112655920 11.958 97 20 40 1 1170657408 54110464 3891024
03:26:14 49929 8192000000 10107158480 10535501824 1141593808 12.231 100 27 54 0 1196127184 49103728 5429648
03:28:06 75276 8192000000 10079141024 10526289920 1100687984 11.844 96 11 21 0 1140043728 37291552 2064192
03:30:22 175171 8192000000 10123685600 10573811712 1150286128 12.312 100 32 64 0 1191084016 33768336 7029552
03:34:17 69916 8192000000 10076827248 10573045760 1153033312 12.338 100 11 22 0 1219232736 63948352 2251072
03:36:27 66936 8192000000 10091863520 10565120000 1142264032 12.237 98 19 39 0 1190215360 44024272 3927056
03:38:34 72996 8192000000 10121439488 10572292096 1130486864 12.126 97 9 18 0 1192384032 59924624 1972544
03:40:48 56955 8192000000 10101531584 10596700160 1113794528 11.968 97 21 42 0 1184192224 66001744 4395952
03:42:50 70358 8192000000 10100936304 10539794432 1110489008 11.937 97 16 31 0 1150000496 36341680 3169808
03:45:00 141161 8192000000 10088646736 10546212864 1091512000 11.757 96 24 48 1 1138831024 42233008 5086016
03:48:23 58429 8192000000 10100723344 10602491904 1128954880 12.112 99 11 22 0 1238060144 106664624 2440640
03:50:25 88589 8192000000 10102855648 10566922240 1128113696 12.104 99 14 28 0 1183963680 53190160 2659824
03:53:18 93473 8192000000 10050264512 10494144512 1115540880 11.985 98 19 38 1 1151447008 31765536 4140592
03:55:57 19605 8192000000 10052084768 10480496640 1118883888 12.016 99 38 76 1 1147922000 21009264 8028848
03:57:17 70442 8192000000 10022547072 10504175616 1053223632 11.392 94 11 22 0 1106027920 50814160 1990128
03:59:29 51218 8192000000 10056782912 10492850176 1090995792 11.752 98 29 58 1 1140288448 43154320 6138336
04:02:07 77313 8192000000 10036466832 10485903360 1079466336 11.642 98 15 30 0 1143765776 61070208 3229232
04:04:25 55876 8192000000 10040983200 10489040896 1110695328 11.939 100 19 38 0 1145577360 30863104 4018928
04:06:40 18209 8192000000 10120557504 10550628352 1127000704 12.093 100 38 76 0 1197860528 63120416 7739408
04:08:01 99644 8192000000 10134683264 10582618112 1104339584 11.879 97 13 25 1 1251422416 144382320 2700512
04:10:43 10918 8192000000 10064914560 10485231616 1121676192 12.043 100 42 83 1 1153818896 24227072 7915632
04:11:55 50070 8192000000 10126641600 10617114624 1117559600 12.004 99 45 90 0 1204462144 78207760 8694784
04:14:32 40610 8192000000 10064316560 10513104896 1135988032 12.178 100 25 51 0 1174410512 33043008 5379472
04:16:19 87781 8192000000 10056588864 10499588096 1124934928 12.074 99 40 80 2 1181692528 48088608 8668992
04:18:50 23061 8192000000 10096848960 10542727168 1133770048 12.157 100 44 87 0 1187276112 44464144 9041920
04:20:29 48085 8192000000 10097102048 10560471040 1143363440 12.247 100 15 30 0 1199656768 53023008 3270320
04:22:17 48833 8192000000 10072156384 10562076672 1132662368 12.146 99 3 6 1 1188711872 55421120 628384
04:24:08 35759 8192000000 10101703728 10549362688 1134056400 12.160 99 34 68 0 1188652944 47187296 7409248
04:25:44 51882 8192000000 10070809824 10521550848 1096513712 11.805 97 19 38 1 1159462880 58805728 4143440
04:28:02 19203 8192000000 10040393760 10472538112 1082182784 11.668 97 28 54 0 1115101232 27396016 5522432
04:29:25 52609 8192000000 10113533216 10561462272 1106312272 11.897 99 41 82 0 1178494528 63827920 8354336
04:31:18 87016 8192000000 10068261680 10513829888 1057541808 11.433 94 21 42 1 1122563232 60282016 4739408
04:33:50 85501 8192000000 10073282768 10524311552 1105184528 11.887 99 13 26 0 1155703952 48016672 2502752
04:36:27 60387 8192000000 9122732672 9446469632 265759136 3.142 24 6 11 1 297179248 30411296 1008816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-10-2021 04:40:08 to 30-10-2021 04:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 88 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2021 04:40:23 to 30-10-2021 04:40:25.

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          231792
MON$OLDEST_ACTIVE               233162
MON$OLDEST_SNAPSHOT             233162
MON$NEXT_TRANSACTION            233162
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                       2040800
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.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 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 ---
--- end of diff output ---

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

30.10.21 04:40:26. 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-30 04:40:29 No severe PSQL-related problems occured
30.10.21 04:40:30. End of report.