Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.08.2021 19: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 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 16440364 kB
MemAvailable 30984580 kB
Buffers 0 kB
Cached 15188888 kB
SwapCached 2568 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-08 19:37:40
DTS_END                         2021-08-08 19:37:40.3020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35452
TRN_ID                          236052
ATT_ID                          618
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: 9206.13 23361 1104736
customer order: creation 169.63 376 20355
customer order: refuse 14.70 343 1764
order to supplier: creation 44.02 1288 5282
order to supplier: removal 3.34 115333 401
invoice (draft): creation 45.11 2828 5413
invoice (draft): removal 53.21 806 6385
invoice accept: apply 41.51 17930 4981
invoice accept: cancel 48.51 693 5821
customer reserve: creation 3822.97 178 458756
customer reserve: removal 4670.00 65 560400
realization accept: apply 81.98 174 9837
realization accept: cancel 84.93 114 10192
payment from customer: creation 58.05 106 6966
payment from customer: removal 5.40 71 648
payment to supplier: creation 48.28 103 5793
payment to supplier: removal 3.34 102 401
service: total inventory turnovers 4.21 1252 505
service: total monetary turnovers 6.08 29 730
service: aggregate perf. data 0.85 18044 102
service: refresh index statistics 0.03 307381 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-08-2021 19:38:30 to 08-08-2021 19:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8470
TEST_TIME 2 6349
TEST_TIME 3 5641
TEST_TIME 4 5579
TEST_TIME 5 5402
TEST_TIME 6 5483
TEST_TIME 7 5909
TEST_TIME 8 5713
TEST_TIME 9 5588
TEST_TIME 10 5805
TEST_TIME 11 6102
TEST_TIME 12 6231
TEST_TIME 13 6585
TEST_TIME 14 6709
TEST_TIME 15 6667
TEST_TIME 16 7294
TEST_TIME 17 7595
TEST_TIME 18 7681
TEST_TIME 19 7811
TEST_TIME 20 8280
TEST_TIME 21 8678
TEST_TIME 22 9064
TEST_TIME 23 9306
TEST_TIME 24 9466
TEST_TIME 25 9733
TEST_TIME 26 10898
TEST_TIME 27 11198
TEST_TIME 28 10979
TEST_TIME 29 10803
TEST_TIME 30 10725
TEST_TIME 31 10550
TEST_TIME 32 10486
TEST_TIME 33 10525
TEST_TIME 34 10336
TEST_TIME 35 10215
TEST_TIME 36 10116
TEST_TIME 37 10040
TEST_TIME 38 10041
TEST_TIME 39 10016
TEST_TIME 40 9806
TEST_TIME 41 9729
TEST_TIME 42 9557
TEST_TIME 43 9511
TEST_TIME 44 9376
TEST_TIME 45 9313
TEST_TIME 46 9323
TEST_TIME 47 9497
TEST_TIME 48 9485
TEST_TIME 49 9498
TEST_TIME 50 9419
TEST_TIME 51 9554
TEST_TIME 52 9560
TEST_TIME 53 9606
TEST_TIME 54 9588
TEST_TIME 55 9551
TEST_TIME 56 9536
TEST_TIME 57 9605
TEST_TIME 58 9647
TEST_TIME 59 9616
TEST_TIME 60 9496
TEST_TIME 61 9478
TEST_TIME 62 9393
TEST_TIME 63 9331
TEST_TIME 64 9338
TEST_TIME 65 9293
TEST_TIME 66 9221
TEST_TIME 67 9310
TEST_TIME 68 9392
TEST_TIME 69 9336
TEST_TIME 70 9348
TEST_TIME 71 9465
TEST_TIME 72 9419
TEST_TIME 73 9418
TEST_TIME 74 9419
TEST_TIME 75 9435
TEST_TIME 76 9540
TEST_TIME 77 9508
TEST_TIME 78 9585
TEST_TIME 79 9521
TEST_TIME 80 9440
TEST_TIME 81 9376
TEST_TIME 82 9405
TEST_TIME 83 9351
TEST_TIME 84 9308
TEST_TIME 85 9251
TEST_TIME 86 9243
TEST_TIME 87 9188
TEST_TIME 88 9172
TEST_TIME 89 9220
TEST_TIME 90 9243
TEST_TIME 91 9224
TEST_TIME 92 9196
TEST_TIME 93 9230
TEST_TIME 94 9287
TEST_TIME 95 9253
TEST_TIME 96 9276
TEST_TIME 97 9312
TEST_TIME 98 9309
TEST_TIME 99 9310
TEST_TIME 100 9305
TEST_TIME 101 9337
TEST_TIME 102 9356
TEST_TIME 103 9361
TEST_TIME 104 9342
TEST_TIME 105 9264
TEST_TIME 106 9302
TEST_TIME 107 9259
TEST_TIME 108 9234
TEST_TIME 109 9238
TEST_TIME 110 9223
TEST_TIME 111 9198
TEST_TIME 112 9207
TEST_TIME 113 9150
TEST_TIME 114 9218
TEST_TIME 115 9223
TEST_TIME 116 9220
TEST_TIME 117 9210
TEST_TIME 118 9215
TEST_TIME 119 9219
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-08-2021 19:38:34 to 08-08-2021 19: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 1528905 1528905 0 0.00 0 21323 6 0 0
doc_list_biud 1528900 1528900 0 0.00 0 61432 30 0 0
sp_add_invoice_to_stock 5156 4981 175 3.39 40 208601 17931 0 175
sp_cancel_adding_invoice 5821 5821 0 0.00 3 59321 694 0 0
sp_cancel_client_order 1764 1764 0 0.00 4 11377 343 0 0
sp_cancel_customer_reserve 560400 560400 0 0.00 1 29071 66 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 5872 72 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 5861 102 0 0
sp_cancel_supplier_invoice 6397 6385 12 0.18 1 61438 807 0 12
sp_cancel_supplier_order 401 401 0 0.00 5 1039296 115334 0 0
sp_cancel_write_off 10192 10192 0 0.00 1 23458 115 0 0
sp_client_order 20355 20355 0 0.00 3 29463 377 0 0
sp_customer_reserve 458756 458756 0 0.00 0 66049 179 0 0
sp_fill_shopping_cart 31035 31035 0 0.00 1 10760 36 0 0
sp_fill_shopping_cart_clo_res 458733 458733 0 0.00 0 13693 14 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 21209 345 0 0
sp_kill_cost_storno 17062 17062 0 0.00 0 11385 56 0 0
sp_kill_qstorno_ret_qs2qd 1137778 1137778 0 0.00 0 61431 35 0 0
sp_kill_qty_storno 599746 599746 0 0.00 0 61432 75 0 0
sp_lock_selected_doc 20425 20425 0 0.00 0 6484 4 0 0
sp_make_cost_storno 27542 27542 0 0.00 0 21319 43 0 0
sp_multiply_rows_for_pdistr 27542 27542 0 0.00 0 8156 26 0 0
sp_multiply_rows_for_qdistr 20355 20355 0 0.00 0 16100 155 0 0
sp_pay_from_customer 6966 6966 0 0.00 4 18510 106 0 0
sp_pay_to_supplier 5793 5793 0 0.00 5 14053 103 0 0
sp_payment_common 12759 12759 0 0.00 1 18214 27 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 11371 313 0 0
sp_qd_handle_on_invoice_upd_sts 10767 10767 0 0.00 0 19770 309 0 0
sp_qd_handle_on_reserve_upd_sts 9837 9837 0 0.00 0 18928 71 0 0
sp_reserve_write_off 9837 9837 0 0.00 6 21332 175 0 0
sp_supplier_invoice 5413 5413 0 0.00 6 66295 2828 0 0
sp_supplier_order 5282 5282 0 0.00 5 29450 1289 0 0
srv_aggregate_perf_data 102 102 0 0.00 2583 147566 18045 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1886 145276 47071 0 0
srv_find_qd_qs_mism 1057434 1057434 0 0.00 0 19809 5 0 0
srv_make_invnt_saldo 505 505 0 0.00 43 61838 1253 0 0
srv_make_money_saldo 730 730 0 0.00 1 2897 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 139685 430306 307381 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4981 4981 0 0.00 0 11856 145 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 6388 102 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 2034 9 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 1356 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 766 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 562 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 3384 17 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 25213 1666 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 5998 9 0 0
t$perf-norm:sp_client_order 20355 20355 0 0.00 0 11128 18 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 669 4 0 0
t$perf-norm:sp_pay_from_customer 6966 6966 0 0.00 0 9662 11 0 0
t$perf-norm:sp_pay_to_supplier 5793 5793 0 0.00 0 5424 9 0 0
t$perf-norm:sp_reserve_write_off 9837 9837 0 0.00 0 12227 8 0 0
t$perf-norm:sp_supplier_invoice 5413 5413 0 0.00 0 9136 18 0 0
t$perf-norm:sp_supplier_order 5282 5282 0 0.00 0 3992 12 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 195 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 62 2 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 1718 22 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 2531 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10242 9892 350 3.41 0 14024 20 0 350
v_all_customers 37224 37224 0 0.00 0 19 1 0 0
v_all_suppliers 29704 29704 0 0.00 0 27 1 0 0
v_all_wares 40710 40710 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1332 7 0 0
v_cancel_client_order 3528 3528 0 0.00 0 4415 7 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 5864 12 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 408 3 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1400 6 0 0
v_cancel_supplier_invoice 730 706 24 3.28 0 507 13 0 24
v_cancel_supplier_order 802 802 0 0.00 0 2124 8 0 0
v_cancel_write_off 1904 1904 0 0.00 0 4509 15 0 0
v_max_id_clo_ord 5281 5281 0 0.00 0 2179 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 3367 18 0 0
v_max_id_ord_sup 5398 5398 0 0.00 0 5346 6 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 473 3 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 1491 4 0 0
v_min_id_clo_ord 5281 5281 0 0.00 0 4800 7 0 0
v_min_id_clo_res 981 981 0 0.00 0 810 3 0 0
v_min_id_ord_sup 5398 5398 0 0.00 0 4674 7 0 0
v_min_non_paid_invoice 5793 5793 0 0.00 0 6912 13 0 0
v_min_non_paid_realizn 6966 6966 0 0.00 0 10237 19 0 0
v_our_firm 10184 10184 0 0.00 0 15 1 0 0
v_reserve_write_off 19674 19674 0 0.00 0 12301 7 0 0
x_lock_depdocs_on_canc_invoice 11853 11853 0 0.00 0 14502 119 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 6331 322 0 0
x_make_qstorno_1000_1200 5282 5282 0 0.00 1 29359 1221 0 0
x_make_qstorno_1200_2000 5413 5413 0 0.00 3 65938 2694 0 0
x_make_qstorno_2100_3300 458756 458756 0 0.00 0 66048 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-08-2021 19:38:38 to 08-08-2021 19: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
17:07:44 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:44 20670 8192000000 10356958176 10673844224 1409536864 14.680 100 90 180 0 1444875456 5679520 29659072
17:10:13 27452 8192000000 10437678112 10765676544 1482521744 15.323 100 85 169 1 1517148992 7591456 27035792
17:11:45 16180 8192000000 10486909600 10826895360 1539394208 15.818 100 87 174 0 1576550688 10210448 26946032
17:13:09 13394 8192000000 10563182704 10920198144 1622803184 16.534 100 75 149 1 1655840768 10422176 22615408
17:14:30 14645 8192000000 10639042064 11009671168 1693429376 17.130 100 74 148 0 1726521232 11484176 21607680
17:15:46 6272 8192000000 10703783072 11081527296 1764634864 17.723 100 84 168 2 1802031200 13169792 24226544
17:16:55 2398 8192000000 10759186992 11140833280 1816817728 18.152 100 84 168 0 1854129616 14714992 22596896
17:17:59 1533 8192000000 10796715104 11187367936 1859835664 18.502 100 87 176 1 1897586880 12211216 25540000
17:19:03 37072 8192000000 10801121184 11213111296 1877863952 18.648 100 57 114 1 1903901408 9721664 16315792
17:20:41 4082 8192000000 10802760240 11227734016 1891537504 18.758 100 31 63 2 1907360960 6769984 9053472
17:21:51 5218 8192000000 10820889792 11249426432 1904054240 18.859 100 23 46 2 1925067328 14846736 6166352
17:23:01 32061 8192000000 10826853872 11263541248 1916233328 18.957 100 30 58 2 1930242304 5628240 8380736
17:24:35 4248 8192000000 10843197104 11271557120 1923007744 19.011 100 23 43 1 1935310848 6137760 6165344
17:25:41 5630 8192000000 10833067712 11272851456 1924153120 19.020 100 27 54 0 1938412912 6439408 7820384
17:26:49 70758 8192000000 10822111824 11258073088 1869226496 18.578 97 29 57 0 1882144032 4981584 7935952
17:29:02 4969 8192000000 10782014928 11204739072 1860962112 18.511 100 35 70 2 1877950384 6958432 10029840
17:30:07 16191 8192000000 10728181104 11139145728 1734052864 17.469 95 35 69 1 1750189200 5532464 10603872
17:31:24 54727 8192000000 10722738864 11132080128 1764241744 17.719 97 35 68 4 1780268784 5308352 10718688
17:33:20 3647 8192000000 10744948640 11160522752 1821977040 18.194 100 29 58 2 1836798720 6877296 7944384
17:34:25 10094 8192000000 10758856624 11188887552 1850363120 18.425 100 29 57 1 1864659616 5816736 8479760
17:35:38 15315 8192000000 10773937648 11202883584 1858763936 18.493 100 14 28 2 1868300384 5746144 3790304
17:36:56 39213 8192000000 10836708608 11231322112 1880179840 18.667 100 64 125 4 1904934608 6091712 18663056
17:38:42 28076 8192000000 10850594208 11272536064 1916315136 18.957 100 41 83 2 1938717856 11038560 11364160
17:40:15 26608 8192000000 10869982240 11294371840 1936009152 19.115 100 52 104 0 1967582992 16486784 15087056
17:41:47 15470 8192000000 10882861600 11313176576 1947341168 19.205 100 40 79 3 1976225024 17268848 11615008
17:43:07 3345 8192000000 10911048448 11340677120 1970789984 19.392 100 60 120 1 2008926368 19778640 18357744
17:44:18 15635 8192000000 10920101936 11350437888 1973910368 19.416 100 40 80 1 2010786704 23548160 13328176
17:45:36 5881 8192000000 10940188576 11374477312 1990932528 19.551 100 52 104 1 2032211168 25873552 15405088
17:46:48 6294 8192000000 10968746624 11392626688 2004568224 19.659 100 67 133 3 2054208816 29725136 19915456
17:48:03 4266 8192000000 10982137584 11413426176 2022929456 19.803 100 68 134 3 2076123152 33298912 19894784
17:49:16 9903 8192000000 11002393552 11438567424 2029216448 19.852 100 51 101 1 2085499152 39993296 16289408
17:50:40 2327 8192000000 11047255376 11471347712 2051201120 20.025 100 86 171 0 2127867008 51030288 25635600
17:51:51 2121 8192000000 11010864048 11437285376 2003787536 19.653 99 78 154 0 2082138656 54227520 24123600
17:53:03 1884 8192000000 11021809072 11446431744 2019041520 19.773 100 82 165 0 2110628912 65233072 26354320
17:54:11 3421 8192000000 11015015648 11443499008 2023861616 19.810 100 79 159 3 2103195360 54050960 25282784
17:55:22 12998 8192000000 10977352208 11423473664 1999216688 19.617 100 43 86 1 2072380352 58772864 14390800
17:56:37 11632 8192000000 10959279184 11403198464 1962920272 19.329 99 36 72 2 2043851616 68206784 12724560
17:57:53 5301 8192000000 10966529344 11401842688 1953103072 19.251 99 57 114 1 2039473344 66781664 19588608
17:59:04 11650 8192000000 10944989584 11391827968 1956941984 19.282 100 53 106 0 2036268032 61493264 17832784
18:00:26 24392 8192000000 10939809088 11388182528 1954418432 19.262 100 32 64 0 2021244192 55990688 10835072
18:01:52 28704 8192000000 10887780656 11347980288 1872654448 18.606 97 17 34 1 1938362416 58487536 7220432
18:03:21 22285 8192000000 10899792752 11349245952 1932929248 19.090 100 18 36 1 1989185568 49964112 6292208
18:04:47 21339 8192000000 10851806736 11285614592 1862824080 18.526 97 41 79 2 1886884400 11949360 12110960
18:06:19 51332 8192000000 10865058080 11308777472 1930809680 19.073 100 40 76 1 1950364320 9764256 9790384
18:08:36 48771 8192000000 10869692160 11310583808 1915565872 18.951 98 43 85 0 1940134432 12838208 11730352
18:10:24 65668 8192000000 10855206448 11326652416 1915067456 18.947 99 5 10 0 1932344128 16242816 1033856
18:12:36 122765 8192000000 10867979376 11327004672 1926873936 19.042 99 24 47 3 1943668064 10620608 6173520
18:15:47 49723 8192000000 10851650416 11311624192 1902134976 18.843 98 12 24 2 1918202352 12786768 3280608
18:18:55 46320 8192000000 10892298816 11347066880 1960276912 19.308 100 29 59 1 1983183328 13917616 8988800
18:20:46 47655 8192000000 10911879168 11379265536 1976113440 19.434 100 17 34 1 2003091040 22034192 4943408
18:22:35 103600 8192000000 10892628272 11372978176 1978130288 19.450 100 13 26 0 1997118032 15282496 3705248
18:25:20 96673 8192000000 10912529312 11379277824 1968692112 19.375 99 8 16 1 1987004176 15899120 2412944
18:28:03 45824 8192000000 10922815984 11394863104 1982504480 19.485 99 35 70 0 2007342144 14508960 10328704
18:30:10 77968 8192000000 10884506112 11353456640 1903874800 18.857 96 14 28 2 1922121216 14527280 3719136
18:32:31 19359 8192000000 10931388128 11379871744 1980136624 19.466 100 45 90 1 2004996160 12742368 12117168
18:33:54 145276 8192000000 10903759520 11374321664 1855432800 18.466 93 14 28 0 1877517216 18147040 3937376
18:37:22 109396 8192000000 10899737376 11345338368 1907926592 18.890 97 34 68 0 1926081264 8602688 9551984
18:40:22 62554 8192000000 10884083808 11354402816 1950854048 19.233 99 21 42 1 1966231456 9323392 6054016
18:42:24 40762 8192000000 10890602880 11377766400 1956495408 19.278 99 8 16 0 1968788784 10304976 1988400
18:44:13 51639 8192000000 10914652224 11394359296 1976228240 19.435 100 10 21 1 1999183504 20036336 2918928
18:46:08 68421 8192000000 10898691664 11358945280 1914553520 18.943 97 22 44 0 1930342912 9728112 6061280
18:48:27 64261 8192000000 10869321808 11334770688 1882267568 18.683 96 12 24 0 1903734480 18614928 2851984
18:50:36 30149 8192000000 10884600992 11330871296 1880358656 18.668 96 30 60 2 1899997376 11510288 8128432
18:52:09 61966 8192000000 10900138160 11365994496 1902317152 18.845 97 21 42 1 1932384896 23802880 6264864
18:54:18 108743 8192000000 10861844560 11320098816 1784644784 17.888 92 6 12 0 1809938544 24128976 1164784
18:57:14 95630 8192000000 10868847744 11328151552 1904812384 18.865 98 27 54 1 1925893312 13176192 7904736
19:01:20 48269 8192000000 10857509920 11332362240 1934266992 19.101 100 8 16 1 1947285216 10601360 2416864
19:03:13 93756 8192000000 10871541824 11350175744 1935403808 19.110 99 10 20 1 1950730400 12337136 2989456
19:05:50 77800 8192000000 10847228496 11331153920 1882598416 18.686 97 12 24 2 1900296272 14107536 3590320
19:08:09 43573 8192000000 10869173968 11334021120 1916263936 18.957 98 13 23 3 1925604864 5993664 3347264
19:09:56 94949 8192000000 10867586560 11340615680 1908596048 18.895 98 18 35 1 1924068144 10719600 4752496
19:12:32 23373 8192000000 10899502608 11352768512 1946377936 19.198 99 31 62 1 1963542736 8536208 8628592
19:13:59 48118 8192000000 10910655008 11380359168 1951944928 19.242 99 25 50 0 1971799040 13189072 6665040
19:16:03 53697 8192000000 10930035952 11385667584 1986745552 19.518 100 24 48 2 2005002352 11208400 7048400
19:17:57 17714 8192000000 10938917872 11394801664 1981126032 19.474 99 43 86 2 2004686560 11987264 11573264
19:19:22 68064 8192000000 10917728304 11385868288 1972505200 19.405 99 15 30 0 1986240512 9325008 4410304
19:21:51 72232 8192000000 10914095056 11365658624 1950044384 19.227 98 33 66 1 1967143648 7075328 10023936
19:24:43 35146 8192000000 10894261904 11357245440 1938625776 19.136 98 25 48 3 1955578240 9860176 7092288
19:26:22 74728 8192000000 10849374464 11334832128 1910414352 18.910 98 8 16 0 1926000464 13380880 2205232
19:28:37 57516 8192000000 10877561920 11359272960 1941417728 19.158 99 14 28 0 1962503824 16781616 4304480
19:30:41 80296 8192000000 10881327792 11375595520 1961014464 19.314 100 10 20 0 1983412752 19512160 2886128
19:34:03 30085 8192000000 10897267072 11362877440 1952346016 19.245 99 33 66 1 1970986240 8999040 9641184
19:35:39 73845 8192000000 10869814960 11325575168 1858801504 18.494 94 26 52 0 1878834256 12511104 7521648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-08-2021 19:38:44 to 08-08-2021 19:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-08-2021 19:39:00 to 08-08-2021 19:39:02.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234852
MON$OLDEST_ACTIVE               236416
MON$OLDEST_SNAPSHOT             236416
MON$NEXT_TRANSACTION            236416
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-03 16:41:55.3470
MON$PAGES                       2009216
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

08.08.21 19:39:03. There are 3 logs in /var/tmp/logs-oltp30 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-08-08 19:39:05 No severe PSQL-related problems occured
08.08.21 19:39:06. End of report.