Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.2021 16: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 16520648 kB
MemAvailable 31552828 kB
Buffers 0 kB
Cached 15116736 kB
SwapCached 0 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-11-20 16:37:56
DTS_END                         2021-11-20 16:37:56.3970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39710
TRN_ID                          238628
ATT_ID                          633
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: 9368.54 19740 1124225
customer order: creation 174.43 341 20931
customer order: refuse 14.58 328 1750
order to supplier: creation 46.18 1264 5542
order to supplier: removal 3.48 110497 418
invoice (draft): creation 45.76 3455 5491
invoice (draft): removal 55.49 807 6659
invoice accept: apply 40.79 17072 4895
invoice accept: cancel 49.81 835 5977
customer reserve: creation 3799.69 167 455963
customer reserve: removal 4824.63 63 578956
realization accept: apply 83.56 177 10027
realization accept: cancel 102.57 118 12308
payment from customer: creation 58.34 122 7001
payment from customer: removal 5.64 105 677
payment to supplier: creation 48.53 144 5824
payment to supplier: removal 3.53 81 424
service: total inventory turnovers 4.61 1197 553
service: total monetary turnovers 6.18 24 741
service: aggregate perf. data 0.70 26720 84
service: refresh index statistics 0.03 231288 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2021 16:38:55 to 20-11-2021 16:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4246
TEST_TIME 2 3840
TEST_TIME 3 4677
TEST_TIME 4 4872
TEST_TIME 5 4858
TEST_TIME 6 4480
TEST_TIME 7 4775
TEST_TIME 8 5010
TEST_TIME 9 4832
TEST_TIME 10 4839
TEST_TIME 11 5043
TEST_TIME 12 5405
TEST_TIME 13 5451
TEST_TIME 14 5481
TEST_TIME 15 5645
TEST_TIME 16 5786
TEST_TIME 17 5887
TEST_TIME 18 6360
TEST_TIME 19 6489
TEST_TIME 20 6986
TEST_TIME 21 7021
TEST_TIME 22 7764
TEST_TIME 23 7949
TEST_TIME 24 8016
TEST_TIME 25 8337
TEST_TIME 26 8492
TEST_TIME 27 9181
TEST_TIME 28 10266
TEST_TIME 29 10487
TEST_TIME 30 10404
TEST_TIME 31 10512
TEST_TIME 32 10594
TEST_TIME 33 10540
TEST_TIME 34 10571
TEST_TIME 35 10379
TEST_TIME 36 10220
TEST_TIME 37 10067
TEST_TIME 38 10117
TEST_TIME 39 10234
TEST_TIME 40 10176
TEST_TIME 41 10080
TEST_TIME 42 10056
TEST_TIME 43 10083
TEST_TIME 44 10023
TEST_TIME 45 9939
TEST_TIME 46 9981
TEST_TIME 47 9821
TEST_TIME 48 9725
TEST_TIME 49 9731
TEST_TIME 50 9711
TEST_TIME 51 9626
TEST_TIME 52 9566
TEST_TIME 53 9555
TEST_TIME 54 9507
TEST_TIME 55 9597
TEST_TIME 56 9582
TEST_TIME 57 9562
TEST_TIME 58 9569
TEST_TIME 59 9475
TEST_TIME 60 9415
TEST_TIME 61 9381
TEST_TIME 62 9365
TEST_TIME 63 9396
TEST_TIME 64 9427
TEST_TIME 65 9512
TEST_TIME 66 9507
TEST_TIME 67 9514
TEST_TIME 68 9570
TEST_TIME 69 9558
TEST_TIME 70 9533
TEST_TIME 71 9681
TEST_TIME 72 9615
TEST_TIME 73 9702
TEST_TIME 74 9695
TEST_TIME 75 9641
TEST_TIME 76 9674
TEST_TIME 77 9583
TEST_TIME 78 9537
TEST_TIME 79 9540
TEST_TIME 80 9462
TEST_TIME 81 9417
TEST_TIME 82 9396
TEST_TIME 83 9392
TEST_TIME 84 9397
TEST_TIME 85 9363
TEST_TIME 86 9303
TEST_TIME 87 9431
TEST_TIME 88 9424
TEST_TIME 89 9437
TEST_TIME 90 9434
TEST_TIME 91 9443
TEST_TIME 92 9457
TEST_TIME 93 9538
TEST_TIME 94 9507
TEST_TIME 95 9510
TEST_TIME 96 9585
TEST_TIME 97 9589
TEST_TIME 98 9615
TEST_TIME 99 9586
TEST_TIME 100 9567
TEST_TIME 101 9516
TEST_TIME 102 9462
TEST_TIME 103 9459
TEST_TIME 104 9459
TEST_TIME 105 9415
TEST_TIME 106 9370
TEST_TIME 107 9347
TEST_TIME 108 9338
TEST_TIME 109 9312
TEST_TIME 110 9306
TEST_TIME 111 9269
TEST_TIME 112 9283
TEST_TIME 113 9296
TEST_TIME 114 9267
TEST_TIME 115 9275
TEST_TIME 116 9296
TEST_TIME 117 9382
TEST_TIME 118 9377
TEST_TIME 119 9394
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-11-2021 16:38:58 to 20-11-2021 16:39:02.

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 1547458 1547458 0 0.00 0 16925 6 0 0
doc_list_biud 1547457 1547457 0 0.00 0 25283 30 0 0
sp_add_invoice_to_stock 4995 4895 100 2.00 52 148217 17073 0 100
sp_cancel_adding_invoice 5977 5977 0 0.00 5 88837 836 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 18472 329 0 0
sp_cancel_customer_reserve 578956 578956 0 0.00 1 21423 64 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 7755 105 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 2 3530 82 0 0
sp_cancel_supplier_invoice 6669 6659 10 0.14 1 31989 807 0 10
sp_cancel_supplier_order 418 418 0 0.00 6 1237339 110498 0 0
sp_cancel_write_off 12308 12308 0 0.00 1 24276 118 0 0
sp_client_order 20931 20931 0 0.00 3 27917 341 0 0
sp_customer_reserve 455963 455963 0 0.00 0 86474 167 0 0
sp_fill_shopping_cart 31961 31961 0 0.00 1 23835 37 0 0
sp_fill_shopping_cart_clo_res 455952 455952 0 0.00 0 15298 13 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 17424 258 0 0
sp_kill_cost_storno 19386 19386 0 0.00 0 7692 58 0 0
sp_kill_qstorno_ret_qs2qd 1177297 1177297 0 0.00 0 25149 34 0 0
sp_kill_qty_storno 620979 620979 0 0.00 0 25283 74 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 9376 5 0 0
sp_make_cost_storno 27736 27736 0 0.00 0 13112 55 0 0
sp_multiply_rows_for_pdistr 27736 27736 0 0.00 0 8233 28 0 0
sp_multiply_rows_for_qdistr 20931 20931 0 0.00 0 11777 158 0 0
sp_pay_from_customer 7001 7001 0 0.00 4 20527 122 0 0
sp_pay_to_supplier 5824 5824 0 0.00 4 14847 144 0 0
sp_payment_common 12825 12825 0 0.00 1 20518 33 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 18465 285 0 0
sp_qd_handle_on_invoice_upd_sts 10861 10861 0 0.00 0 24233 342 0 0
sp_qd_handle_on_reserve_upd_sts 10027 10027 0 0.00 0 25282 55 0 0
sp_reserve_write_off 10027 10027 0 0.00 6 25292 177 0 0
sp_supplier_invoice 5491 5491 0 0.00 7 87262 3456 0 0
sp_supplier_order 5542 5542 0 0.00 5 114049 1265 0 0
srv_aggregate_perf_data 85 84 1 1.17 2967 672019 26720 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1521 144308 50668 0 0
srv_find_qd_qs_mism 1077259 1077259 0 0.00 0 16923 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 21 137468 1197 0 0
srv_make_money_saldo 741 741 0 0.00 0 1555 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 124846 276235 231289 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4895 4895 0 0.00 0 20079 158 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 3459 122 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 1988 11 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 674 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 346 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 2135 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 579 10 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26132 1538 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 3952 12 0 0
t$perf-norm:sp_client_order 20931 20931 0 0.00 0 12697 17 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1363 5 0 0
t$perf-norm:sp_pay_from_customer 7001 7001 0 0.00 0 9327 11 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 7530 8 0 0
t$perf-norm:sp_reserve_write_off 10027 10027 0 0.00 0 9504 13 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 9884 21 0 0
t$perf-norm:sp_supplier_order 5542 5542 0 0.00 0 7175 18 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 1073 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 168 3 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 677 19 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 2924 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4434 1111 0 0
v_add_invoice_to_stock 9966 9766 200 2.00 0 5267 16 0 200
v_all_customers 38288 38288 0 0.00 0 18 1 0 0
v_all_suppliers 30554 30554 0 0.00 0 14 1 0 0
v_all_wares 41862 41862 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 324 6 0 0
v_cancel_client_order 3500 3500 0 0.00 0 10488 10 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 7537 21 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 992 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 3382 11 0 0
v_cancel_supplier_invoice 866 846 20 2.30 0 6758 23 0 20
v_cancel_supplier_order 836 836 0 0.00 0 1096 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 10214 18 0 0
v_max_id_clo_ord 5540 5540 0 0.00 0 679 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 4940 20 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 4715 5 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 3883 5 0 0
v_max_non_paid_realizn 3568 3568 0 0.00 0 4273 9 0 0
v_min_id_clo_ord 5540 5540 0 0.00 0 4701 9 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 6955 16 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 3463 7 0 0
v_min_non_paid_invoice 5824 5824 0 0.00 0 14721 23 0 0
v_min_non_paid_realizn 7001 7001 0 0.00 0 7885 21 0 0
v_our_firm 10440 10440 0 0.00 0 23 1 0 0
v_reserve_write_off 20054 20054 0 0.00 0 10119 6 0 0
x_lock_depdocs_on_canc_invoice 12213 12213 0 0.00 0 18719 108 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 14223 424 0 0
x_make_qstorno_1000_1200 5542 5542 0 0.00 1 111829 1189 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 2 87254 3321 0 0
x_make_qstorno_2100_3300 455963 455963 0 0.00 0 86473 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2021 16:39:03 to 20-11-2021 16:39:07.

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
14:08:00 3 8192000000 8801737536 8813973504 7763408 0.094 2 2 3 0 7904496 66144 74944
14:09:01 25507 8192000000 9910471136 10223374336 985430944 10.737 100 84 168 0 1013714368 6131104 22152320
14:10:36 15621 8192000000 9952264112 10278182912 1040372832 11.268 100 87 173 1 1070780736 8569920 21837984
14:11:59 21456 8192000000 9994086128 10330939392 1079319840 11.641 100 87 174 0 1110025312 9419792 21285680
14:13:25 15982 8192000000 10040452256 10389278720 1131803776 12.138 100 81 162 2 1161584704 10336592 19444336
14:14:49 10995 8192000000 10092870176 10456850432 1185685184 12.643 100 81 162 2 1215544512 12004432 17854896
14:16:02 6465 8192000000 10137161216 10506297344 1216266912 12.927 100 78 157 0 1247043072 13495808 17280352
14:17:11 3971 8192000000 10160653840 10541350912 1247759984 13.218 100 82 165 2 1279840720 12679728 19401008
14:18:17 2545 8192000000 10191941616 10576662528 1277725472 13.492 100 88 178 1 1312142928 14667648 19749808
14:19:22 1832 8192000000 10206130272 10591166464 1292011216 13.623 100 89 179 0 1326140528 14757680 19371632
14:20:26 1219 8192000000 10217481936 10607669248 1303189760 13.724 100 95 190 1 1336891840 11322032 22380048
14:21:29 4060 8192000000 10199163200 10609594368 1309700848 13.783 100 43 87 0 1327504656 8337648 9466160
14:22:34 4123 8192000000 10212097760 10624360448 1317005488 13.850 100 33 66 1 1330770752 6102720 7662544
14:23:39 9376 8192000000 10200673744 10622271488 1320451120 13.881 100 13 26 0 1328780256 5806416 2522720
14:24:50 78622 8192000000 10227180592 10647912448 1330235104 13.969 100 21 42 1 1340220688 5238512 4747072
14:27:10 4560 8192000000 10229270448 10644135936 1329067344 13.959 100 31 62 0 1341884112 5569488 7247280
14:28:16 7075 8192000000 10205072112 10622558208 1280392480 13.517 97 16 32 3 1289127232 5347296 3387456
14:29:25 9393 8192000000 10165327552 10559582208 1238774176 13.135 97 23 46 1 1249788320 5547344 5466800
14:30:35 39872 8192000000 10142949408 10555846656 1232888640 13.081 97 27 54 1 1244103568 5118976 6095952
14:32:20 7561 8192000000 10141599152 10545356800 1214030176 12.906 95 31 62 1 1226656688 5135488 7491024
14:33:29 8880 8192000000 10158859760 10559250432 1262109488 13.349 100 31 62 0 1275209472 4985872 8114112
14:34:38 51344 8192000000 10182416528 10590851072 1288941296 13.595 100 38 75 3 1302411776 4768576 8701904
14:36:35 5278 8192000000 10193903504 10607325184 1302792208 13.721 100 34 68 3 1317110544 6917824 7400512
14:37:43 13776 8192000000 10206591520 10619977728 1310834864 13.794 100 20 40 0 1321344928 6120512 4389552
14:39:10 11310 8192000000 10250869392 10657656832 1338146544 14.041 100 59 117 2 1361042416 9509376 13386496
14:40:23 9678 8192000000 10260904064 10673614848 1350649472 14.153 100 52 104 3 1373637984 10118576 12869936
14:41:36 35139 8192000000 10267753904 10693152768 1364098976 14.274 100 31 62 1 1381857568 10689344 7069248
14:43:21 12553 8192000000 10295978336 10717724672 1385844656 14.469 100 48 96 2 1412360544 15783856 10732032
14:44:41 21287 8192000000 10320176512 10743062528 1397764608 14.575 100 50 100 1 1426973104 17544704 11663792
14:46:09 22508 8192000000 10328197936 10753916928 1406448576 14.652 100 53 106 0 1442144928 22126096 13570256
14:47:39 7890 8192000000 10350573792 10772512768 1408117120 14.667 99 56 110 1 1447425456 25850944 13457392
14:48:57 5583 8192000000 10352753008 10772701184 1420578880 14.778 100 71 139 0 1466337072 28694720 17063472
14:50:10 1518 8192000000 10350464464 10772267008 1404717392 14.637 99 69 136 0 1453173600 31652320 16803888
14:51:16 3485 8192000000 10375854000 10794065920 1429991760 14.861 100 78 155 1 1486967152 38026832 18948560
14:52:25 3212 8192000000 10386469200 10798137344 1425862560 14.825 100 75 150 3 1488046480 43265328 18918592
14:53:34 13693 8192000000 10346268288 10760896512 1381515968 14.430 97 52 104 0 1447407904 51612736 14279200
14:55:00 2599 8192000000 10373116384 10792030208 1401505712 14.608 99 56 111 0 1468960432 52821616 14633104
14:56:10 3174 8192000000 10364018672 10774851584 1400043472 14.595 100 71 142 0 1466023200 47294640 18685088
14:57:17 2508 8192000000 10377327968 10783793152 1402418816 14.617 100 87 173 0 1477933040 52462944 23051280
14:58:28 13830 8192000000 10336539104 10756861952 1376185984 14.382 100 40 80 3 1439626240 51857488 11582768
14:59:45 14573 8192000000 10344382064 10781700096 1379399168 14.411 99 35 70 1 1443392704 54256528 9737008
15:01:06 11366 8192000000 10328869856 10754588672 1376374304 14.384 100 37 74 2 1436100048 49182464 10543280
15:02:21 17428 8192000000 10339327632 10776801280 1383108928 14.444 100 36 72 0 1445616256 52826352 9680976
15:03:44 24299 8192000000 10328466992 10762371072 1355723104 14.199 99 28 56 0 1421836688 57448304 8665280
15:05:35 22087 8192000000 10289723824 10734657536 1345823440 14.110 99 24 48 0 1395735424 43654496 6257488
15:06:58 47813 8192000000 10265713408 10706219008 1339483168 14.053 99 34 68 1 1363912320 16560336 7868816
15:08:55 72829 8192000000 10282174960 10747604992 1353907040 14.183 99 11 22 0 1380350320 23965504 2477776
15:11:09 93752 8192000000 10274936000 10718982144 1321022672 13.886 97 14 29 1 1342749520 19097888 2628960
15:13:46 89136 8192000000 10301566352 10739572736 1364964752 14.282 99 24 48 0 1389909312 19557232 5387328
15:16:25 77262 8192000000 10290267856 10736848896 1375997936 14.381 100 8 16 0 1389104832 10951312 2155584
15:18:47 104534 8192000000 10297030064 10758221824 1386461696 14.474 100 21 42 1 1402574784 11455856 4657232
15:21:39 32792 8192000000 10292598592 10741592064 1363967392 14.273 98 13 26 2 1378324176 10756528 3600256
15:23:14 78785 8192000000 10300616608 10749255680 1377161792 14.391 99 17 34 2 1392475248 11685792 3627664
15:26:44 37286 8192000000 10329482320 10767806464 1382616960 14.440 99 48 92 1 1401831680 9086976 10127744
15:28:25 89954 8192000000 10318149936 10771918848 1387217184 14.481 99 24 48 2 1403689728 11097184 5375360
15:30:56 42623 8192000000 10319650096 10775244800 1388308384 14.491 99 30 60 0 1415324608 19245760 7770464
15:32:40 62042 8192000000 10282587328 10745262080 1372194576 14.347 99 10 20 0 1391232624 16435600 2602448
15:34:53 42276 8192000000 10269641856 10731704320 1339142848 14.050 97 11 22 0 1350521232 9008448 2369936
15:36:41 85011 8192000000 10263134576 10728710144 1333438592 13.998 97 24 48 0 1349860688 11030592 5391504
15:39:19 39825 8192000000 10265198320 10714185728 1336636912 14.027 98 14 28 0 1345550096 6102672 2810512
15:41:11 65815 8192000000 10287139936 10753011712 1379823232 14.415 100 14 28 0 1392601520 9796176 2982112
15:43:17 88415 8192000000 10283915600 10731339776 1334598400 14.009 97 24 48 0 1351938864 12239616 5100848
15:45:46 47882 8192000000 10252684288 10692603904 1304510944 13.736 97 29 57 0 1318761936 7526592 6724400
15:47:37 73914 8192000000 10254617568 10714226688 1329008800 13.958 98 19 38 1 1346936336 13373840 4553696
15:49:59 59638 8192000000 10268615712 10723250176 1343829776 14.092 99 28 56 1 1366447616 16562480 6055360
15:52:04 38415 8192000000 10280892368 10710441984 1339412496 14.052 99 27 54 0 1355318816 10095040 5811280
15:53:43 121077 8192000000 10256752128 10706227200 1344361104 14.097 99 22 44 0 1356865360 7103840 5400416
15:57:01 81577 8192000000 10294022416 10741850112 1367778032 14.307 99 33 66 2 1390063200 13467936 8817232
15:59:35 67284 8192000000 10296930096 10756571136 1377655632 14.396 99 20 41 1 1400918768 18200496 5062640
16:01:43 94964 8192000000 10276934752 10736693248 1337806368 14.038 97 17 34 0 1360845808 18858032 4181408
16:04:40 108775 8192000000 10285567328 10752557056 1370718448 14.333 100 8 16 1 1394735376 21801888 2215040
16:07:28 61091 8192000000 10264353040 10703745024 1326129792 13.932 97 16 32 0 1343930000 14110768 3689440
16:09:37 78676 8192000000 10289422576 10756685824 1379697904 14.414 100 11 22 1 1397252976 15354544 2200528
16:11:55 69594 8192000000 10314793616 10764619776 1368614160 14.315 98 23 46 1 1390732816 17057456 5061200
16:14:12 144308 8192000000 10296590064 10747035648 1318159760 13.860 94 17 34 1 1332569392 10862496 3547136
16:17:40 123534 8192000000 10293495824 10757517312 1392703840 14.530 100 26 52 0 1407365312 8568112 6093360
16:20:53 24865 8192000000 10289708288 10761748480 1386198080 14.472 99 20 38 1 1400995104 10018112 4778912
16:22:22 80416 8192000000 10289303152 10757537792 1357655392 14.216 97 4 8 0 1367482304 9016640 810272
16:24:44 80811 8192000000 10274104656 10740264960 1335609664 14.018 96 13 26 0 1350245680 11735280 2900736
16:27:41 76497 8192000000 10278907424 10739867648 1351737520 14.163 98 16 32 1 1363454400 8578960 3137920
16:30:05 60846 8192000000 10293022480 10754310144 1372892896 14.353 99 21 42 0 1389801424 11642064 5266464
16:32:06 68142 8192000000 10277324048 10728574976 1329636624 13.964 96 15 30 0 1347872848 14605952 3630272
16:34:14 40407 8192000000 10268352560 10724167680 1320746592 13.883 96 15 30 0 1336165184 12216944 3201648
16:36:00 64336 8192000000 10291972896 10754416640 1375713264 14.378 100 36 72 0 1395890016 12463936 7712816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-11-2021 16:39:09 to 20-11-2021 16:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 100 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-11-2021 16:39:24 to 20-11-2021 16:39:26.

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          235134
MON$OLDEST_ACTIVE               238741
MON$OLDEST_SNAPSHOT             238741
MON$NEXT_TRANSACTION            238741
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                       2060512
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Nov 20 16:37:58 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:02 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:05 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:05 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:06 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:06 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:06 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:06 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Sat Nov 20 16:38:17 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
--- end of diff output ---

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

20.11.21 16:39:27. 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-11-20 16:39:29 No severe PSQL-related problems occured
20.11.21 16:39:30. End of report.