Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2022 10: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 16076352 kB
MemAvailable 31302836 kB
Buffers 0 kB
Cached 15574516 kB
SwapCached 1200 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 2022-01-05 10:37:13
DTS_END                         2022-01-05 10:37:13.5930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36232
TRN_ID                          223590
ATT_ID                          578
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: 9132.48 22741 1095898
customer order: creation 175.77 435 21092
customer order: refuse 15.51 359 1861
order to supplier: creation 45.54 1310 5465
order to supplier: removal 3.47 161172 416
invoice (draft): creation 46.53 3130 5583
invoice (draft): removal 52.36 913 6283
invoice accept: apply 41.52 24246 4982
invoice accept: cancel 46.88 1167 5626
customer reserve: creation 3860.49 229 463259
customer reserve: removal 4540.12 97 544814
realization accept: apply 84.90 279 10188
realization accept: cancel 85.70 161 10284
payment from customer: creation 60.74 170 7289
payment from customer: removal 5.74 112 689
payment to supplier: creation 50.85 200 6102
payment to supplier: removal 3.59 114 431
service: total inventory turnovers 3.93 4183 472
service: total monetary turnovers 7.77 330 932
service: aggregate perf. data 1.03 15004 124
service: refresh index statistics 0.05 241207 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 10:38:30 to 05-01-2022 10:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9456
TEST_TIME 2 8257
TEST_TIME 3 6882
TEST_TIME 4 6929
TEST_TIME 5 6860
TEST_TIME 6 7264
TEST_TIME 7 6895
TEST_TIME 8 6629
TEST_TIME 9 6658
TEST_TIME 10 6622
TEST_TIME 11 6758
TEST_TIME 12 6675
TEST_TIME 13 6618
TEST_TIME 14 6639
TEST_TIME 15 6788
TEST_TIME 16 6880
TEST_TIME 17 7056
TEST_TIME 18 7313
TEST_TIME 19 7369
TEST_TIME 20 7578
TEST_TIME 21 7585
TEST_TIME 22 7580
TEST_TIME 23 7782
TEST_TIME 24 7957
TEST_TIME 25 8371
TEST_TIME 26 8698
TEST_TIME 27 8742
TEST_TIME 28 9046
TEST_TIME 29 9317
TEST_TIME 30 9801
TEST_TIME 31 10070
TEST_TIME 32 10587
TEST_TIME 33 11075
TEST_TIME 34 10849
TEST_TIME 35 11135
TEST_TIME 36 10944
TEST_TIME 37 10768
TEST_TIME 38 10726
TEST_TIME 39 10784
TEST_TIME 40 10685
TEST_TIME 41 10633
TEST_TIME 42 10645
TEST_TIME 43 10587
TEST_TIME 44 10583
TEST_TIME 45 10562
TEST_TIME 46 10457
TEST_TIME 47 10440
TEST_TIME 48 10394
TEST_TIME 49 10701
TEST_TIME 50 10530
TEST_TIME 51 10339
TEST_TIME 52 10291
TEST_TIME 53 10167
TEST_TIME 54 10088
TEST_TIME 55 10014
TEST_TIME 56 10059
TEST_TIME 57 9977
TEST_TIME 58 9946
TEST_TIME 59 9976
TEST_TIME 60 9964
TEST_TIME 61 9907
TEST_TIME 62 9923
TEST_TIME 63 9833
TEST_TIME 64 9728
TEST_TIME 65 9684
TEST_TIME 66 9671
TEST_TIME 67 9637
TEST_TIME 68 9541
TEST_TIME 69 9554
TEST_TIME 70 9521
TEST_TIME 71 9493
TEST_TIME 72 9488
TEST_TIME 73 9479
TEST_TIME 74 9497
TEST_TIME 75 9590
TEST_TIME 76 9644
TEST_TIME 77 9581
TEST_TIME 78 9631
TEST_TIME 79 9633
TEST_TIME 80 9643
TEST_TIME 81 9685
TEST_TIME 82 9603
TEST_TIME 83 9551
TEST_TIME 84 9549
TEST_TIME 85 9521
TEST_TIME 86 9452
TEST_TIME 87 9430
TEST_TIME 88 9426
TEST_TIME 89 9373
TEST_TIME 90 9332
TEST_TIME 91 9324
TEST_TIME 92 9275
TEST_TIME 93 9307
TEST_TIME 94 9308
TEST_TIME 95 9288
TEST_TIME 96 9270
TEST_TIME 97 9255
TEST_TIME 98 9312
TEST_TIME 99 9299
TEST_TIME 100 9324
TEST_TIME 101 9334
TEST_TIME 102 9407
TEST_TIME 103 9493
TEST_TIME 104 9468
TEST_TIME 105 9488
TEST_TIME 106 9465
TEST_TIME 107 9421
TEST_TIME 108 9374
TEST_TIME 109 9375
TEST_TIME 110 9347
TEST_TIME 111 9320
TEST_TIME 112 9273
TEST_TIME 113 9227
TEST_TIME 114 9188
TEST_TIME 115 9149
TEST_TIME 116 9156
TEST_TIME 117 9156
TEST_TIME 118 9201
TEST_TIME 119 9185
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2022 10:38:34 to 05-01-2022 10:38: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 1523907 1523907 0 0.00 0 12083 13 0 0
doc_list_biud 1523892 1523892 0 0.00 0 36807 39 0 0
sp_add_invoice_to_stock 5107 4982 125 2.44 73 209102 24246 0 125
sp_cancel_adding_invoice 5626 5626 0 0.00 5 139539 1167 0 0
sp_cancel_client_order 1861 1861 0 0.00 6 14199 359 0 0
sp_cancel_customer_reserve 544814 544814 0 0.00 1 36831 97 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 4 5820 113 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 5269 115 0 0
sp_cancel_supplier_invoice 6294 6283 11 0.17 3 27321 914 0 11
sp_cancel_supplier_order 416 416 0 0.00 8 1400366 161173 0 0
sp_cancel_write_off 10284 10284 0 0.00 2 11083 161 0 0
sp_client_order 21092 21092 0 0.00 6 19612 436 0 0
sp_customer_reserve 463259 463259 0 0.00 1 120004 230 0 0
sp_fill_shopping_cart 32132 32132 0 0.00 1 23070 79 0 0
sp_fill_shopping_cart_clo_res 463223 463223 0 0.00 0 16250 18 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 23366 253 0 0
sp_kill_cost_storno 17030 17030 0 0.00 0 5173 70 0 0
sp_kill_qstorno_ret_qs2qd 1106611 1106611 0 0.00 0 36709 41 0 0
sp_kill_qty_storno 584408 584408 0 0.00 0 36804 95 0 0
sp_lock_selected_doc 21063 21063 0 0.00 0 6081 6 0 0
sp_make_cost_storno 28517 28517 0 0.00 0 9775 70 0 0
sp_multiply_rows_for_pdistr 28515 28515 0 0.00 0 8449 38 0 0
sp_multiply_rows_for_qdistr 21091 21091 0 0.00 0 12338 166 0 0
sp_pay_from_customer 7289 7289 0 0.00 5 12551 170 0 0
sp_pay_to_supplier 6102 6102 0 0.00 6 14783 201 0 0
sp_payment_common 13390 13390 0 0.00 2 7221 41 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 1 6248 283 0 0
sp_qd_handle_on_invoice_upd_sts 10563 10563 0 0.00 0 19497 473 0 0
sp_qd_handle_on_reserve_upd_sts 10187 10187 0 0.00 0 13946 65 0 0
sp_reserve_write_off 10188 10188 0 0.00 8 16929 280 0 0
sp_supplier_invoice 5583 5583 0 0.00 8 85556 3131 0 0
sp_supplier_order 5465 5465 0 0.00 7 31252 1310 0 0
srv_aggregate_perf_data 124 124 0 0.00 2196 165105 15005 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 707 213409 42566 0 0
srv_find_qd_qs_mism 1046914 1046914 0 0.00 0 12081 9 0 0
srv_make_invnt_saldo 472 472 0 0.00 52 86953 4184 0 0
srv_make_money_saldo 932 932 0 0.00 1 14530 331 0 0
srv_recalc_idx_stat 6 6 0 0.00 43520 409264 241207 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4985 4985 0 0.00 1 14917 956 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 7749 494 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 2090 29 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2243 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1553 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 265 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1990 33 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 110287 6428 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2072 21 0 0
t$perf-norm:sp_client_order 21092 21092 0 0.00 0 17021 36 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 464 12 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 5000 18 0 0
t$perf-norm:sp_pay_to_supplier 6102 6102 0 0.00 0 6467 21 0 0
t$perf-norm:sp_reserve_write_off 10188 10188 0 0.00 0 9276 23 0 0
t$perf-norm:sp_supplier_invoice 5583 5583 0 0.00 0 8121 32 0 0
t$perf-norm:sp_supplier_order 5465 5465 0 0.00 0 2862 29 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 2621 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1328 45 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 2260 18 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 574 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 246 87 0 0
v_add_invoice_to_stock 10122 9872 250 2.46 0 11114 35 0 250
v_all_customers 38650 38650 0 0.00 0 64 3 0 0
v_all_suppliers 31188 31188 0 0.00 0 61 4 0 0
v_all_wares 42182 42182 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 249 12 0 0
v_cancel_client_order 3722 3722 0 0.00 0 7004 15 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 5333 18 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 12024 23 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 5200 16 0 0
v_cancel_supplier_invoice 854 832 22 2.57 0 1796 20 0 22
v_cancel_supplier_order 832 832 0 0.00 0 2141 13 0 0
v_cancel_write_off 2028 2028 0 0.00 0 3468 23 0 0
v_max_id_clo_ord 5464 5464 0 0.00 0 265 5 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1932 16 0 0
v_max_id_ord_sup 5576 5576 0 0.00 0 2201 6 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 1548 8 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 870 6 0 0
v_min_id_clo_ord 5464 5464 0 0.00 0 3071 8 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 4139 12 0 0
v_min_id_ord_sup 5576 5576 0 0.00 0 4882 9 0 0
v_min_non_paid_invoice 6100 6100 0 0.00 0 4368 15 0 0
v_min_non_paid_realizn 7288 7288 0 0.00 0 7216 16 0 0
v_our_firm 10652 10652 0 0.00 0 58 4 0 0
v_reserve_write_off 20374 20374 0 0.00 0 13389 18 0 0
x_lock_depdocs_on_canc_invoice 11493 11493 0 0.00 0 22052 127 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 3267 300 0 0
x_make_qstorno_1000_1200 5465 5465 0 0.00 2 31232 1147 0 0
x_make_qstorno_1200_2000 5583 5583 0 0.00 4 84952 2917 0 0
x_make_qstorno_2100_3300 463259 463259 0 0.00 0 120003 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 10:38:38 to 05-01-2022 10:38: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
08:07:17 919 8192000000 8925744784 8981614592 13612304 0.165 4 2 4 0 13823664 113744 97616
08:08:18 28094 8192000000 9720446048 10018414592 787661072 8.771 100 86 172 0 815507728 7288912 20557744
08:09:52 21439 8192000000 9749611984 10052198400 814258784 9.041 100 89 178 0 852431504 16531552 21641168
08:11:16 15095 8192000000 9787941872 10101043200 847147376 9.371 100 89 178 0 891763920 23788144 20828400
08:12:34 13268 8192000000 9857501536 10189262848 904599280 9.944 100 83 164 2 964829504 42443216 17787008
08:13:49 3266 8192000000 9915699408 10253783040 944805440 10.340 100 91 180 1 1023292224 59840624 18646160
08:14:55 2684 8192000000 9951459024 10305609728 975882352 10.644 100 87 171 2 1060917424 67852832 17182240
08:16:00 1813 8192000000 9981035584 10341089280 997748128 10.857 100 95 187 2 1098986160 82173584 19064448
08:17:04 1359 8192000000 10023987104 10387091456 1025145904 11.122 100 92 173 1 1127245952 84599440 17500608
08:18:07 1093 8192000000 10016063856 10387443712 1041864640 11.283 100 98 187 2 1125104256 64442336 18797280
08:19:11 5696 8192000000 10034179440 10413674496 1046647840 11.329 100 75 132 1 1149665280 90999104 12018336
08:20:19 774 8192000000 10030446864 10410102784 1054790096 11.407 100 83 162 0 1139854576 67706608 17357872
08:21:21 1171 8192000000 10060619904 10442616832 1068794896 11.541 100 97 175 1 1167442528 80358608 18289024
08:22:23 1225 8192000000 10063173968 10444787712 1075329616 11.603 100 95 188 3 1168202896 74129712 18743568
08:23:27 1277 8192000000 10076325536 10458984448 1077846624 11.627 100 96 185 0 1182122880 84701360 19574896
08:24:29 1180 8192000000 10074730224 10464808960 1084641792 11.692 100 92 177 1 1182525040 78673504 19209744
08:25:33 1208 8192000000 10073669120 10455764992 1081839120 11.665 99 93 181 2 1182609680 82204176 18566384
08:26:35 1096 8192000000 10075637456 10470793216 1091601296 11.758 100 78 152 1 1186180544 78933136 15646112
08:27:37 1155 8192000000 10073651952 10464677888 1094696320 11.787 100 95 178 1 1187460720 75186240 17578160
08:28:40 1031 8192000000 10034117696 10413899776 1065776448 11.512 100 89 167 0 1142507536 59680192 17050896
08:29:42 1233 8192000000 10045482064 10426675200 1070312592 11.555 100 96 189 1 1156107952 66883216 18912144
08:30:44 1297 8192000000 10036456768 10414981120 1061257472 11.469 100 90 176 1 1143430656 63739328 18433856
08:31:46 1878 8192000000 10031071728 10423627776 1064613552 11.501 100 89 172 4 1144832576 61658496 18560528
08:32:50 1133 8192000000 10015466192 10390081536 1050023856 11.361 100 94 179 2 1124064768 55690128 18350784
08:33:52 1434 8192000000 10007002992 10382856192 1031583216 11.184 100 91 168 1 1119841760 71395600 16862944
08:34:55 1256 8192000000 10000114480 10372665344 1028642624 11.155 100 94 168 2 1111210880 64548112 18020144
08:35:58 1217 8192000000 9979075120 10354589696 1031520672 11.183 100 80 143 3 1092663744 44575328 16567744
08:36:59 2333 8192000000 10000054976 10378604544 1027683776 11.146 100 69 134 2 1102329728 61221248 13424704
08:38:06 12546 8192000000 10011001856 10395131904 1050707056 11.367 100 63 124 1 1123480416 60284240 12489120
08:39:31 1729 8192000000 10040355744 10422575104 1074004192 11.590 100 86 165 0 1151648976 59933552 17711232
08:40:40 802 8192000000 10054607472 10430353408 1076632416 11.615 100 95 189 1 1159945984 63074320 20239248
08:41:48 1441 8192000000 10101048992 10481786880 1098766448 11.826 100 95 183 0 1209499904 91850416 18883040
08:42:52 5848 8192000000 10138796976 10529476608 1116496976 11.994 100 81 159 1 1251100144 117618320 16984848
08:44:02 9130 8192000000 10116890208 10518556672 1131782320 12.138 100 63 126 1 1237317776 92430928 13104528
08:45:15 8690 8192000000 10171070896 10571251712 1146300400 12.275 100 60 115 0 1288999056 130523904 12174752
08:46:28 1118 8192000000 10216224320 10611367936 1160921392 12.412 100 95 185 0 1334023984 153162704 19939888
08:47:41 1630 8192000000 10252109392 10648870912 1164014768 12.441 100 83 165 2 1361628752 178129344 19484640
08:48:46 1373 8192000000 10286969648 10684592128 1182521744 12.614 100 89 172 1 1405537840 204293392 18722704
08:49:50 958 8192000000 10333017504 10734972928 1186898224 12.654 100 94 184 0 1445606912 237550256 21158432
08:50:55 948 8192000000 10383277712 10784702464 1195159296 12.731 100 97 190 0 1491303360 274644816 21499248
08:51:59 1251 8192000000 10403415344 10816724992 1187655312 12.662 100 99 194 3 1514422736 303983648 22783776
08:53:06 1120 8192000000 10430497744 10846687232 1203349824 12.807 100 97 187 3 1539089680 314418240 21321616
08:54:11 1231 8192000000 10465811184 10888347648 1208467664 12.855 100 93 181 0 1576621680 345818144 22335872
08:55:15 1994 8192000000 10481882992 10914680832 1207241984 12.844 100 85 163 0 1591676832 364677104 19757744
08:56:21 1034 8192000000 10553008880 10972651520 1221365904 12.974 100 95 180 1 1658360688 416147984 20846800
08:57:25 1091 8192000000 10588140992 11024490496 1219332976 12.956 100 99 198 1 1693569856 451034400 23202480
08:58:29 2157 8192000000 10598343600 11011817472 1220053824 12.962 100 95 187 0 1706917392 463629760 23233808
08:59:34 701 8192000000 10635105344 11064578048 1219598704 12.958 100 98 194 1 1742028272 497915776 24513792
09:00:36 960 8192000000 10636923408 11082207232 1218803264 12.951 100 95 188 1 1739838576 497963792 23071520
09:01:39 941 8192000000 10651008656 11116900352 1222883568 12.988 100 94 176 0 1751147408 507127648 21136192
09:02:42 4159 8192000000 10644434352 11107610624 1222063840 12.981 100 56 112 0 1763741104 526561136 15116128
09:04:12 10634 8192000000 10589759376 11046105088 1195789952 12.737 100 40 80 0 1701542800 494710928 11041920
09:05:24 23923 8192000000 10574872192 11064131584 1180108336 12.591 99 39 78 0 1673542960 483166960 10267664
09:06:48 33890 8192000000 10464432208 11051532288 1160333664 12.406 99 27 52 3 1575328928 407956592 7038672
09:08:26 27440 8192000000 10335662240 10893848576 1154049536 12.347 100 26 52 2 1508302448 347883136 6369776
09:09:57 43874 8192000000 10112619312 10572365824 1080419952 11.651 94 23 44 1 1202368064 117641280 4306832
09:11:43 19009 8192000000 10135134240 10576269312 1132109376 12.141 99 44 85 3 1223593600 82541488 8942736
09:13:04 77075 8192000000 10092907136 10543947776 1133069808 12.150 99 19 37 3 1191046592 54391664 3585120
09:15:28 50895 8192000000 10091422832 10544234496 1123494480 12.060 99 31 60 1 1180810624 50762736 6553408
09:17:27 144317 8192000000 10062110688 10505097216 1114520016 11.975 98 12 24 0 1163717808 46842032 2355760
09:20:58 131548 8192000000 10095125808 10557501440 1084375344 11.689 95 16 30 2 1119797248 32314544 3107360
09:24:17 120353 8192000000 10121911072 10638409728 1055529632 11.414 93 9 18 0 1165572896 108097472 1945792
09:27:24 152185 8192000000 10090854288 10520682496 1120638848 12.033 99 29 53 0 1158398896 32347856 5412192
09:31:08 44762 8192000000 10096160304 10537062400 1140485792 12.220 100 39 72 2 1197593136 49013408 8093936
09:32:54 52789 8192000000 10110229920 10582892544 1129777632 12.119 99 13 26 2 1198961536 66604320 2579584
09:34:59 60085 8192000000 10141007632 10585571328 1145312880 12.265 100 34 67 1 1229698176 76216912 8168384
09:37:06 213407 8192000000 10047446688 10523099136 1078256064 11.631 94 14 28 0 1120911360 39567280 3088016
09:41:51 74967 8192000000 10076961920 10521694208 1117181120 12.000 98 26 51 1 1164808240 41593264 6033856
09:44:07 48237 8192000000 10049857648 10508648448 1108391312 11.917 98 17 33 0 1161019952 49340480 3288160
09:45:58 94762 8192000000 10071479616 10578518016 1126461712 12.088 99 11 22 0 1171958176 43326736 2169728
09:48:33 45334 8192000000 10118958400 10582016000 1141872688 12.233 99 22 42 0 1195898272 48999472 5026112
09:50:18 21296 8192000000 10167631568 10608656384 1150059376 12.310 100 49 99 0 1261864592 101888672 9916544
09:51:40 114400 8192000000 10088570688 10555265024 1115898112 11.988 97 14 28 1 1214667120 95310304 3458704
09:54:48 40481 8192000000 10137592768 10581626880 1148952176 12.300 100 47 94 2 1225573760 67310112 9311472
09:56:31 15683 8192000000 10091283072 10537619456 1116873216 11.997 98 55 104 2 1183115728 55895520 10346992
09:57:50 43682 8192000000 10081092608 10556383232 1126647312 12.090 99 31 60 2 1206788832 74014944 6126576
09:59:35 107244 8192000000 10080875504 10533433344 1107990064 11.913 98 45 90 1 1189664912 72307232 9367616
10:02:23 30139 8192000000 10076096832 10537984000 1100332336 11.841 98 32 64 1 1146389456 39789952 6267168
10:04:08 75191 8192000000 10063580432 10553159680 1103026656 11.866 98 8 16 1 1163276480 58448976 1800848
10:06:29 73515 8192000000 10047013168 10506940416 1092780352 11.769 97 16 30 1 1131111344 35195168 3135824
10:09:04 77160 8192000000 10083767376 10546528256 1082744800 11.674 97 5 10 0 1139590544 55821056 1024688
10:11:28 51779 8192000000 10083516480 10524254208 1107977728 11.913 98 28 52 1 1149450176 36203024 5269424
10:13:23 22991 8192000000 10123295360 10564231168 1122003920 12.046 99 46 91 1 1209792208 78142224 9646064
10:15:16 20375 8192000000 10093396960 10521124864 1121504512 12.041 99 41 81 2 1182431680 52072880 8854288
10:16:38 19151 8192000000 10126495264 10560016384 1132419264 12.144 100 42 84 0 1227453392 85758928 9275200
10:18:04 26176 8192000000 10150206096 10582474752 1121713776 12.043 99 33 63 2 1238031248 109089584 7227888
10:19:32 135116 8192000000 10102939984 10565091328 1128021920 12.103 99 12 24 0 1162840352 31994800 2823632
10:22:51 58220 8192000000 10093712416 10573262848 1128109024 12.104 99 23 43 4 1179046640 46747952 4189664
10:24:51 78664 8192000000 10088248144 10533113856 1133966976 12.159 99 18 36 0 1180250672 42619264 3664432
10:27:13 37607 8192000000 10045896096 10511491072 1128254224 12.105 100 11 20 0 1166151376 35775312 2121840
10:29:44 73075 8192000000 10127364784 10587406336 1137861040 12.195 99 20 41 0 1201746800 59021216 4864544
10:32:00 66427 8192000000 10153464448 10612027392 1118847536 12.016 97 22 44 0 1233680800 109961728 4871536
10:34:07 59287 8192000000 10095516208 10568298496 1126159136 12.085 99 24 47 1 1221405312 89800528 5445648
10:36:12 29144 8192000000 10112942032 10561867776 1119997040 12.027 98 27 53 0 1183213312 57811792 5404480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-01-2022 10:38:44 to 05-01-2022 10:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 10:39:01 to 05-01-2022 10:39:03.

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          219629
MON$OLDEST_ACTIVE               223700
MON$OLDEST_SNAPSHOT             223700
MON$NEXT_TRANSACTION            223700
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                       2076512
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jan  5 10:37:18 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jan  5 10:37:20 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Jan  5 10:37:20 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

05.01.22 10:39:04. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-05 10:39:06 No severe PSQL-related problems occured
05.01.22 10:39:08. End of report.