Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 13:38
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 16389544 kB
MemAvailable 31421780 kB
Buffers 0 kB
Cached 15332676 kB
SwapCached 436 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-12-27 13:37:07
DTS_END                         2021-12-27 13:37:07.4260
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/39054
TRN_ID                          243456
ATT_ID                          698
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: 9009.01 22456 1081081
customer order: creation 176.87 370 21224
customer order: refuse 15.22 319 1826
order to supplier: creation 47.58 1380 5709
order to supplier: removal 3.62 93735 434
invoice (draft): creation 47.57 3362 5708
invoice (draft): removal 49.28 946 5914
invoice accept: apply 42.96 18636 5155
invoice accept: cancel 44.24 843 5309
customer reserve: creation 3950.43 186 474052
customer reserve: removal 4335.27 61 520233
realization accept: apply 87.18 200 10462
realization accept: cancel 74.38 118 8925
payment from customer: creation 61.55 133 7386
payment from customer: removal 6.08 89 729
payment to supplier: creation 51.98 135 6237
payment to supplier: removal 3.38 147 406
service: total inventory turnovers 4.22 3094 506
service: total monetary turnovers 6.29 606 755
service: aggregate perf. data 0.89 12410 107
service: refresh index statistics 0.03 312340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2021 13:39:12 to 27-12-2021 13:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5918
TEST_TIME 2 6065
TEST_TIME 3 6123
TEST_TIME 4 6795
TEST_TIME 5 6540
TEST_TIME 6 6912
TEST_TIME 7 6770
TEST_TIME 8 6739
TEST_TIME 9 6900
TEST_TIME 10 6836
TEST_TIME 11 6855
TEST_TIME 12 7088
TEST_TIME 13 7183
TEST_TIME 14 7076
TEST_TIME 15 7478
TEST_TIME 16 8029
TEST_TIME 17 8565
TEST_TIME 18 8453
TEST_TIME 19 8482
TEST_TIME 20 8779
TEST_TIME 21 8924
TEST_TIME 22 9141
TEST_TIME 23 9207
TEST_TIME 24 9205
TEST_TIME 25 9809
TEST_TIME 26 10408
TEST_TIME 27 10684
TEST_TIME 28 10457
TEST_TIME 29 10467
TEST_TIME 30 10230
TEST_TIME 31 10178
TEST_TIME 32 10162
TEST_TIME 33 10334
TEST_TIME 34 10297
TEST_TIME 35 10247
TEST_TIME 36 10058
TEST_TIME 37 10022
TEST_TIME 38 10149
TEST_TIME 39 10154
TEST_TIME 40 10150
TEST_TIME 41 10144
TEST_TIME 42 10008
TEST_TIME 43 9890
TEST_TIME 44 9990
TEST_TIME 45 10048
TEST_TIME 46 9935
TEST_TIME 47 9769
TEST_TIME 48 9630
TEST_TIME 49 9651
TEST_TIME 50 9614
TEST_TIME 51 9556
TEST_TIME 52 9502
TEST_TIME 53 9454
TEST_TIME 54 9424
TEST_TIME 55 9368
TEST_TIME 56 9404
TEST_TIME 57 9368
TEST_TIME 58 9243
TEST_TIME 59 9195
TEST_TIME 60 9267
TEST_TIME 61 9280
TEST_TIME 62 9346
TEST_TIME 63 9307
TEST_TIME 64 9303
TEST_TIME 65 9305
TEST_TIME 66 9463
TEST_TIME 67 9487
TEST_TIME 68 9475
TEST_TIME 69 9512
TEST_TIME 70 9472
TEST_TIME 71 9546
TEST_TIME 72 9574
TEST_TIME 73 9539
TEST_TIME 74 9465
TEST_TIME 75 9440
TEST_TIME 76 9384
TEST_TIME 77 9392
TEST_TIME 78 9331
TEST_TIME 79 9257
TEST_TIME 80 9212
TEST_TIME 81 9202
TEST_TIME 82 9210
TEST_TIME 83 9219
TEST_TIME 84 9187
TEST_TIME 85 9185
TEST_TIME 86 9168
TEST_TIME 87 9181
TEST_TIME 88 9239
TEST_TIME 89 9210
TEST_TIME 90 9215
TEST_TIME 91 9235
TEST_TIME 92 9254
TEST_TIME 93 9311
TEST_TIME 94 9329
TEST_TIME 95 9289
TEST_TIME 96 9285
TEST_TIME 97 9246
TEST_TIME 98 9234
TEST_TIME 99 9198
TEST_TIME 100 9176
TEST_TIME 101 9144
TEST_TIME 102 9096
TEST_TIME 103 9072
TEST_TIME 104 9031
TEST_TIME 105 9121
TEST_TIME 106 9092
TEST_TIME 107 9129
TEST_TIME 108 9100
TEST_TIME 109 9101
TEST_TIME 110 9123
TEST_TIME 111 9111
TEST_TIME 112 9124
TEST_TIME 113 9149
TEST_TIME 114 9141
TEST_TIME 115 9175
TEST_TIME 116 9223
TEST_TIME 117 9169
TEST_TIME 118 9100
TEST_TIME 119 9078
TEST_TIME 120 9012

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 13:39:16 to 27-12-2021 13:39:19.

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 1519384 1519384 0 0.00 0 26722 7 0 0
doc_list_biud 1519383 1519383 0 0.00 0 84608 27 0 0
sp_add_invoice_to_stock 5288 5155 133 2.51 16 224712 18637 0 133
sp_cancel_adding_invoice 5309 5309 0 0.00 6 58499 843 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 12468 319 0 0
sp_cancel_customer_reserve 520233 520233 0 0.00 1 40349 62 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 13744 89 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 17553 147 0 0
sp_cancel_supplier_invoice 5922 5914 8 0.13 2 84613 946 0 8
sp_cancel_supplier_order 434 434 0 0.00 7 1018271 93736 0 0
sp_cancel_write_off 8925 8925 0 0.00 1 13726 118 0 0
sp_client_order 21224 21224 0 0.00 4 21755 371 0 0
sp_customer_reserve 474052 474052 0 0.00 0 53244 186 0 0
sp_fill_shopping_cart 32626 32626 0 0.00 1 14599 44 0 0
sp_fill_shopping_cart_clo_res 474049 474049 0 0.00 0 14506 15 0 0
sp_get_clo_for_invoice 5151 5151 0 0.00 0 31311 363 0 0
sp_kill_cost_storno 15369 15369 0 0.00 0 13739 59 0 0
sp_kill_qstorno_ret_qs2qd 1055739 1055739 0 0.00 0 84607 34 0 0
sp_kill_qty_storno 558253 558253 0 0.00 0 84607 73 0 0
sp_lock_selected_doc 21698 21698 0 0.00 0 11306 3 0 0
sp_make_cost_storno 29235 29235 0 0.00 0 14340 62 0 0
sp_multiply_rows_for_pdistr 29235 29235 0 0.00 0 13760 34 0 0
sp_multiply_rows_for_qdistr 21224 21224 0 0.00 0 21718 178 0 0
sp_pay_from_customer 7386 7386 0 0.00 4 20881 133 0 0
sp_pay_to_supplier 6237 6237 0 0.00 5 16752 135 0 0
sp_payment_common 13623 13623 0 0.00 1 13746 33 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 11060 285 0 0
sp_qd_handle_on_invoice_upd_sts 10459 10459 0 0.00 0 31403 316 0 0
sp_qd_handle_on_reserve_upd_sts 10462 10462 0 0.00 0 14112 54 0 0
sp_reserve_write_off 10462 10462 0 0.00 7 17327 200 0 0
sp_supplier_invoice 5708 5708 0 0.00 6 78932 3362 0 0
sp_supplier_order 5709 5709 0 0.00 6 34730 1381 0 0
srv_aggregate_perf_data 107 107 0 0.00 2863 71894 12411 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2680 130238 46936 0 0
srv_find_qd_qs_mism 1031446 1031446 0 0.00 0 26720 6 0 0
srv_make_invnt_saldo 506 506 0 0.00 43 107589 3095 0 0
srv_make_money_saldo 755 755 0 0.00 1 15995 606 0 0
srv_recalc_idx_stat 4 4 0 0.00 163069 624048 312341 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 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-norm:sp_add_invoice_to_stock 5155 5155 0 0.00 0 17536 160 0 0
t$perf-norm:sp_cancel_adding_invoice 465 465 0 0.00 0 3476 69 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 2660 14 0 0
t$perf-norm:sp_cancel_customer_reserve 767 767 0 0.00 0 955 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1138 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 2507 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 461 461 0 0.00 0 7554 37 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 26655 1293 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 6932 16 0 0
t$perf-norm:sp_client_order 21224 21224 0 0.00 0 13814 21 0 0
t$perf-norm:sp_customer_reserve 1101 1101 0 0.00 0 2677 8 0 0
t$perf-norm:sp_pay_from_customer 7386 7386 0 0.00 0 9043 9 0 0
t$perf-norm:sp_pay_to_supplier 6237 6237 0 0.00 0 3984 8 0 0
t$perf-norm:sp_reserve_write_off 10462 10462 0 0.00 0 11021 11 0 0
t$perf-norm:sp_supplier_invoice 5708 5708 0 0.00 0 18333 23 0 0
t$perf-norm:sp_supplier_order 5709 5709 0 0.00 0 5914 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1493 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 153 3 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 10184 38 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 7045 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10566 10300 266 2.51 0 17671 17 0 266
v_all_customers 38840 38840 0 0.00 0 17 1 0 0
v_all_suppliers 31916 31916 0 0.00 0 51 1 0 0
v_all_wares 42448 42448 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 930 930 0 0.00 0 9336 15 0 0
v_cancel_client_order 3652 3652 0 0.00 0 5529 8 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 2837 8 0 0
v_cancel_customer_reserve 1534 1534 0 0.00 0 2335 6 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 17353 34 0 0
v_cancel_supplier_invoice 938 922 16 1.70 0 1994 17 0 16
v_cancel_supplier_order 868 868 0 0.00 0 315 4 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2298 17 0 0
v_max_id_clo_ord 5702 5702 0 0.00 0 133 1 0 0
v_max_id_clo_res 1101 1101 0 0.00 0 1933 15 0 0
v_max_id_ord_sup 5699 5699 0 0.00 0 5298 7 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 759 2 0 0
v_max_non_paid_realizn 4002 4002 0 0.00 0 3966 4 0 0
v_min_id_clo_ord 5702 5702 0 0.00 0 5064 7 0 0
v_min_id_clo_res 1101 1101 0 0.00 0 8474 19 0 0
v_min_id_ord_sup 5699 5699 0 0.00 0 5848 8 0 0
v_min_non_paid_invoice 6237 6237 0 0.00 0 13740 24 0 0
v_min_non_paid_realizn 7386 7386 0 0.00 0 13739 26 0 0
v_our_firm 10376 10376 0 0.00 0 13 1 0 0
v_reserve_write_off 20922 20922 0 0.00 0 17003 9 0 0
x_lock_depdocs_on_canc_invoice 10762 10762 0 0.00 0 15549 109 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 8641 254 0 0
x_make_qstorno_1000_1200 5709 5709 0 0.00 2 34721 1301 0 0
x_make_qstorno_1200_2000 5708 5708 0 0.00 3 78918 3197 0 0
x_make_qstorno_2100_3300 474052 474052 0 0.00 0 53242 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 13:39:20 to 27-12-2021 13:39:24.

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
11:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 33880 8192000000 9724293024 10022686720 801532400 8.912 100 86 173 0 829826544 7842048 20452096
11:09:52 22397 8192000000 9770038960 10079170560 842056816 9.320 100 84 168 0 874323120 12499408 19766896
11:11:17 17732 8192000000 9807576784 10129412096 878807264 9.688 100 77 154 0 915370688 19161296 17402128
11:12:50 12813 8192000000 9885571776 10220011520 930114912 10.196 100 77 154 1 995824816 49283536 16426368
11:14:07 7889 8192000000 9931251328 10276880384 967789024 10.565 100 84 166 0 1051433264 66439632 17204608
11:15:16 4924 8192000000 9964114992 10314346496 1000016224 10.879 100 85 171 0 1085939232 68841168 17081840
11:16:23 3275 8192000000 10003078000 10363207680 1022586784 11.097 100 83 167 0 1112565408 72898544 17080080
11:17:28 2238 8192000000 10032933296 10399330304 1046981280 11.332 100 92 183 0 1146869840 81970192 17918368
11:18:32 1550 8192000000 10027158320 10395770880 1063692480 11.492 100 91 181 1 1140469488 57427456 19349552
11:19:34 5917 8192000000 9985594816 10363744256 1070073152 11.553 100 68 131 0 1099412880 15131024 14208704
11:20:41 9836 8192000000 9981997104 10372308992 1076830832 11.617 100 32 63 1 1110295328 26804272 6660224
11:21:52 4709 8192000000 10017698384 10407559168 1089942928 11.742 100 54 108 0 1134071664 32732112 11396624
11:22:59 11202 8192000000 10010982096 10409984000 1095184256 11.792 100 26 53 0 1118943296 18155984 5603056
11:24:11 4363 8192000000 10022785696 10410729472 1102560992 11.862 100 66 133 0 1131384880 14214432 14609456
11:25:16 8146 8192000000 10024920032 10432757760 1102803056 11.864 100 36 72 0 1127939296 17486192 7650048
11:26:26 6656 8192000000 10020275904 10418843648 1079362880 11.641 98 41 82 2 1106365440 17956192 9046368
11:27:36 6907 8192000000 9999275888 10404679680 1083959408 11.685 99 35 70 0 1101395344 9907712 7528224
11:28:46 6480 8192000000 9971711472 10352234496 1028337520 11.152 98 31 62 1 1071232096 36275472 6619104
11:29:55 25461 8192000000 9949520448 10337878016 995426704 10.834 94 20 40 0 1031634800 32331328 3876768
11:31:21 2456 8192000000 9944603440 10322944000 1029071120 11.159 100 60 115 2 1053092000 11172160 12848720
11:32:26 7700 8192000000 9961278592 10352992256 1030643360 11.175 99 39 75 0 1056773920 17916048 8214512
11:33:34 57934 8192000000 9957209488 10345959424 1049031728 11.351 100 39 78 2 1063690544 5763968 8894848
11:35:35 4660 8192000000 9982758928 10372169728 1057944048 11.437 100 45 87 4 1090039424 21874176 10221200
11:36:42 7091 8192000000 9973110992 10372534272 1063817184 11.493 100 34 69 0 1092235152 20437680 7980288
11:37:53 9338 8192000000 10026936560 10417057792 1095977792 11.799 100 48 94 2 1129054432 23579760 9496880
11:39:05 60469 8192000000 10049843136 10454175744 1107367056 11.907 100 37 74 3 1163100640 48181584 7552000
11:41:10 2707 8192000000 10120775280 10519089152 1121585936 12.042 100 62 124 1 1215880640 81397392 12897312
11:42:17 30678 8192000000 10119201824 10529275904 1130390672 12.125 100 43 86 0 1226949200 87402320 9156208
11:43:56 19267 8192000000 10147720864 10550169600 1141341696 12.228 100 69 133 1 1244817904 89470288 14005920
11:45:21 7330 8192000000 10155036448 10563956736 1140086304 12.216 99 48 96 0 1257742240 106500528 11155408
11:46:31 15919 8192000000 10187507760 10600550400 1150733600 12.316 100 52 101 0 1307950608 145406944 11810064
11:48:06 7816 8192000000 10208146736 10609766400 1147064704 12.282 99 55 111 0 1317072800 157615408 12392688
11:49:31 7975 8192000000 10245942880 10660257792 1166826880 12.467 100 63 124 0 1343514176 162653024 14034272
11:50:44 7714 8192000000 10248979520 10660208640 1157275184 12.378 100 54 106 1 1355021456 185585360 12160912
11:52:00 2679 8192000000 10280872944 10679083008 1137552880 12.193 100 73 144 1 1373949216 220084640 16311696
11:53:10 4989 8192000000 10258331408 10651537408 1135324080 12.172 99 81 161 2 1353478560 200041200 18113280
11:54:21 9503 8192000000 10251559968 10675048448 1137090752 12.188 99 51 102 0 1358535600 209596288 11848560
11:55:32 3828 8192000000 10264989184 10662989824 1133005264 12.150 99 67 132 2 1355588144 207181312 15401568
11:56:40 4315 8192000000 10322399760 10721988608 1139875728 12.214 100 72 143 0 1415816272 259069968 16870576
11:57:49 7433 8192000000 10264196672 10666938368 1112401808 11.955 99 54 109 1 1361218080 235626544 13189728
11:59:00 12568 8192000000 10314608176 10757464064 1141771104 12.232 100 32 64 4 1405963248 255551584 8640560
12:00:19 17708 8192000000 10316764928 10752417792 1135888720 12.177 100 29 58 0 1415438800 272026864 7523216
12:01:38 26760 8192000000 10315894928 10773532672 1124121296 12.066 100 23 46 0 1425686304 295638416 5926592
12:03:53 45172 8192000000 10090765232 10528808960 1107176048 11.906 100 14 28 0 1259932224 149187760 3568416
12:06:23 10969 8192000000 10137420512 10597711872 1133660416 12.156 100 24 48 1 1249474032 110407952 5405664
12:07:54 95621 8192000000 10122399648 10643943424 1123000560 12.055 100 19 39 1 1282826144 155315744 4509840
12:10:59 104399 8192000000 10063523376 10527805440 1106538624 11.900 98 11 22 0 1144482160 36059152 1884384
12:13:47 21596 8192000000 10070385536 10487390208 1104538816 11.881 97 51 101 0 1150927520 35508848 10879856
12:15:08 116082 8192000000 10094695152 10595495936 1115462208 11.984 99 10 20 1 1189923968 72731840 1729920
12:18:05 88634 8192000000 10089287312 10557075456 1142408288 12.238 100 9 18 0 1213429008 69003056 2017664
12:21:02 93731 8192000000 10113253440 10593263616 1142814704 12.242 99 15 30 0 1193542752 47790352 2937696
12:23:36 130235 8192000000 10117026272 10557812736 1153329072 12.341 100 19 36 0 1195618032 37768528 4520432
12:26:49 73229 8192000000 10077071232 10532077568 1141734896 12.232 99 18 36 0 1179148272 33565856 3847520
12:29:03 12098 8192000000 10083462048 10518679552 1144703696 12.260 100 55 109 0 1177039568 21344720 10991152
12:30:19 49201 8192000000 10107349760 10553434112 1136585872 12.183 99 28 56 0 1206418080 63422896 6409312
12:32:16 50734 8192000000 10077255840 10587582464 1147148976 12.283 100 18 36 1 1200672320 49012496 4510848
12:34:08 85326 8192000000 10083511200 10549874688 1092990864 11.771 95 16 33 0 1141973168 45175024 3807280
12:36:33 24210 8192000000 10122636672 10606456832 1134040544 12.159 100 23 46 1 1230077760 91275936 4761280
12:38:01 47495 8192000000 10044648976 10490744832 1073910608 11.589 95 17 34 1 1093255824 16165488 3179728
12:39:55 27497 8192000000 10036432976 10459000832 1070352624 11.555 95 19 35 1 1097336176 23476144 3507408
12:41:24 45648 8192000000 10101933088 10539622400 1105133984 11.886 99 31 62 0 1187224800 75921312 6169504
12:43:15 71366 8192000000 10092442960 10520109056 1107058656 11.905 99 8 16 0 1157827888 48853312 1915920
12:45:28 54162 8192000000 10072723504 10503004160 1084045264 11.686 96 23 46 2 1154093904 65157104 4891536
12:47:24 45110 8192000000 10112913664 10557370368 1113508016 11.966 99 37 72 1 1190860352 69831696 7520640
12:49:21 52932 8192000000 10064129088 10480734208 1071951376 11.571 96 26 52 0 1101472256 24742128 4778752
12:51:17 90756 8192000000 10069927600 10540945408 1105293776 11.888 99 37 74 1 1184232240 71205504 7732960
12:54:11 63675 8192000000 10019799120 10473250816 1082295056 11.669 97 21 42 0 1136200016 49318496 4586464
12:56:15 69728 8192000000 10090225440 10529230848 1120024800 12.027 99 14 28 1 1162976224 39911056 3040368
12:58:28 72120 8192000000 10103823968 10588635136 1126168688 12.085 100 16 32 0 1230327040 100491120 3667232
13:00:42 38528 8192000000 10058950400 10494451712 1121370752 12.040 99 27 53 1 1182861520 56414064 5076704
13:02:31 58498 8192000000 10081554704 10552807424 1096879648 11.808 97 21 39 2 1156147248 54542816 4724784
13:04:30 63443 8192000000 10093697008 10558611456 1123157520 12.057 99 36 72 1 1182394192 51311056 7925616
13:06:35 98099 8192000000 10100926576 10547920896 1130976032 12.131 99 22 42 2 1180129904 45224704 3929168
13:09:14 70358 8192000000 10115519408 10591670272 1131313056 12.134 98 12 24 1 1177460048 44075024 2071968
13:11:52 49060 8192000000 10104460816 10540294144 1142890384 12.243 99 29 58 1 1184340448 35373696 6076368
13:13:49 115665 8192000000 10088223280 10567852032 1152768032 12.335 100 40 80 1 1224118112 62642592 8707488
13:16:47 25855 8192000000 10093849600 10580414464 1144599520 12.259 99 11 22 0 1196650224 49978192 2072512
13:18:14 72119 8192000000 10099645632 10553245696 1150948880 12.318 99 11 22 0 1198758064 45064224 2744960
13:20:26 47255 8192000000 10125874768 10595348480 1142952960 12.243 98 14 28 0 1222121952 76302496 2866496
13:23:27 24058 8192000000 10086299232 10525265920 1150090016 12.310 100 28 56 0 1188240928 31708128 6442784
13:24:55 66772 8192000000 10070291824 10516635648 1069060592 11.543 93 16 32 1 1108555056 35840512 3653952
13:27:11 45194 8192000000 10073954704 10519715840 1084615648 11.691 96 41 82 0 1141037952 48136544 8285760
13:29:04 63341 8192000000 10059324352 10491088896 1044959696 11.312 94 37 74 1 1127526608 75190288 7376624
13:31:08 62990 8192000000 10107918976 10540466176 1114054736 11.971 100 21 42 0 1191552192 72520272 4977184
13:33:17 83451 8192000000 10040526176 10509336576 1087377008 11.718 97 7 14 1 1128238432 38386976 2474448
13:36:50 24455 8192000000 8978493536 9185054720 151166128 1.811 14 2 4 0 157397936 5918208 313600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2021 13:39:26 to 27-12-2021 13:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 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 27-12-2021 13:39:42 to 27-12-2021 13:39:44.

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          243735
MON$OLDEST_ACTIVE               243736
MON$OLDEST_SNAPSHOT             243736
MON$NEXT_TRANSACTION            243736
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                       2060128
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             821
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Mon Dec 27 13:37:16 2021
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
:268: 
:269: mscs-test-161.budzdorov.ru    Mon Dec 27 13:37:20 2021
:270:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:271: 
--- end of diff output ---

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

27.12.21 13:39:45. 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-12-27 13:39:47 No severe PSQL-related problems occured
27.12.21 13:39:49. End of report.