Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2021 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 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 16116916 kB
MemAvailable 31053016 kB
Buffers 0 kB
Cached 15594632 kB
SwapCached 2596 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-12 10:37:05
DTS_END                         2021-08-12 10:37:05.6910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42224
TRN_ID                          235523
ATT_ID                          687
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: 9141.28 21461 1096953
customer order: creation 170.55 387 20466
customer order: refuse 14.90 313 1788
order to supplier: creation 45.15 1496 5418
order to supplier: removal 3.36 112141 403
invoice (draft): creation 44.54 3337 5345
invoice (draft): removal 52.17 847 6260
invoice accept: apply 40.75 18255 4890
invoice accept: cancel 47.40 880 5688
customer reserve: creation 3754.19 180 450503
customer reserve: removal 4673.98 64 560877
realization accept: apply 82.66 176 9919
realization accept: cancel 83.16 129 9979
payment from customer: creation 60.29 114 7235
payment from customer: removal 6.04 59 725
payment to supplier: creation 47.46 126 5695
payment to supplier: removal 3.60 73 432
service: total inventory turnovers 3.89 3394 467
service: total monetary turnovers 6.17 551 740
service: aggregate perf. data 0.98 13342 118
service: refresh index statistics 0.04 273351 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2021 10:38:49 to 12-08-2021 10:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9117
TEST_TIME 2 8658
TEST_TIME 3 7196
TEST_TIME 4 6784
TEST_TIME 5 6420
TEST_TIME 6 5938
TEST_TIME 7 6523
TEST_TIME 8 6705
TEST_TIME 9 6980
TEST_TIME 10 7185
TEST_TIME 11 7045
TEST_TIME 12 7006
TEST_TIME 13 7374
TEST_TIME 14 7324
TEST_TIME 15 7388
TEST_TIME 16 7903
TEST_TIME 17 8017
TEST_TIME 18 8616
TEST_TIME 19 8526
TEST_TIME 20 8584
TEST_TIME 21 8900
TEST_TIME 22 9172
TEST_TIME 23 9207
TEST_TIME 24 9337
TEST_TIME 25 9369
TEST_TIME 26 10025
TEST_TIME 27 10330
TEST_TIME 28 11020
TEST_TIME 29 10903
TEST_TIME 30 11071
TEST_TIME 31 10880
TEST_TIME 32 10882
TEST_TIME 33 10762
TEST_TIME 34 10502
TEST_TIME 35 10280
TEST_TIME 36 10237
TEST_TIME 37 10151
TEST_TIME 38 10129
TEST_TIME 39 9969
TEST_TIME 40 9824
TEST_TIME 41 9735
TEST_TIME 42 9743
TEST_TIME 43 9792
TEST_TIME 44 9732
TEST_TIME 45 9699
TEST_TIME 46 9719
TEST_TIME 47 9830
TEST_TIME 48 9692
TEST_TIME 49 9775
TEST_TIME 50 9877
TEST_TIME 51 9799
TEST_TIME 52 9754
TEST_TIME 53 9871
TEST_TIME 54 9859
TEST_TIME 55 9902
TEST_TIME 56 9927
TEST_TIME 57 10017
TEST_TIME 58 9856
TEST_TIME 59 9789
TEST_TIME 60 9685
TEST_TIME 61 9629
TEST_TIME 62 9632
TEST_TIME 63 9587
TEST_TIME 64 9479
TEST_TIME 65 9487
TEST_TIME 66 9449
TEST_TIME 67 9438
TEST_TIME 68 9399
TEST_TIME 69 9379
TEST_TIME 70 9376
TEST_TIME 71 9529
TEST_TIME 72 9565
TEST_TIME 73 9547
TEST_TIME 74 9582
TEST_TIME 75 9556
TEST_TIME 76 9567
TEST_TIME 77 9636
TEST_TIME 78 9610
TEST_TIME 79 9508
TEST_TIME 80 9438
TEST_TIME 81 9465
TEST_TIME 82 9373
TEST_TIME 83 9377
TEST_TIME 84 9337
TEST_TIME 85 9265
TEST_TIME 86 9273
TEST_TIME 87 9243
TEST_TIME 88 9229
TEST_TIME 89 9214
TEST_TIME 90 9220
TEST_TIME 91 9201
TEST_TIME 92 9268
TEST_TIME 93 9262
TEST_TIME 94 9247
TEST_TIME 95 9275
TEST_TIME 96 9382
TEST_TIME 97 9383
TEST_TIME 98 9406
TEST_TIME 99 9377
TEST_TIME 100 9308
TEST_TIME 101 9270
TEST_TIME 102 9251
TEST_TIME 103 9242
TEST_TIME 104 9216
TEST_TIME 105 9219
TEST_TIME 106 9187
TEST_TIME 107 9148
TEST_TIME 108 9198
TEST_TIME 109 9237
TEST_TIME 110 9206
TEST_TIME 111 9230
TEST_TIME 112 9284
TEST_TIME 113 9279
TEST_TIME 114 9299
TEST_TIME 115 9347
TEST_TIME 116 9336
TEST_TIME 117 9269
TEST_TIME 118 9238
TEST_TIME 119 9222
TEST_TIME 120 9149

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-08-2021 10:38:52 to 12-08-2021 10:38:56.

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 1514662 1514662 0 0.00 0 18961 7 0 0
doc_list_biud 1514660 1514660 0 0.00 0 25730 30 0 0
sp_add_invoice_to_stock 5002 4890 112 2.23 38 168361 18255 0 112
sp_cancel_adding_invoice 5688 5688 0 0.00 6 65169 881 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 8218 314 0 0
sp_cancel_customer_reserve 560877 560877 0 0.00 1 25735 65 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 3 1516 60 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 2868 73 0 0
sp_cancel_supplier_invoice 6269 6260 9 0.14 2 20964 847 0 9
sp_cancel_supplier_order 403 403 0 0.00 14 1036734 112142 0 0
sp_cancel_write_off 9979 9979 0 0.00 1 10046 129 0 0
sp_client_order 20466 20466 0 0.00 4 48745 387 0 0
sp_customer_reserve 450503 450503 0 0.00 0 140487 181 0 0
sp_fill_shopping_cart 31226 31226 0 0.00 1 15210 41 0 0
sp_fill_shopping_cart_clo_res 450492 450492 0 0.00 0 12851 15 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 23528 348 0 0
sp_kill_cost_storno 16824 16824 0 0.00 0 6063 59 0 0
sp_kill_qstorno_ret_qs2qd 1138396 1138396 0 0.00 0 25727 35 0 0
sp_kill_qty_storno 599792 599792 0 0.00 0 25730 74 0 0
sp_lock_selected_doc 20681 20681 0 0.00 0 10021 6 0 0
sp_make_cost_storno 27728 27728 0 0.00 0 18955 60 0 0
sp_multiply_rows_for_pdistr 27727 27727 0 0.00 0 8095 33 0 0
sp_multiply_rows_for_qdistr 20466 20466 0 0.00 0 10283 192 0 0
sp_pay_from_customer 7235 7235 0 0.00 4 16223 114 0 0
sp_pay_to_supplier 5695 5695 0 0.00 5 18619 127 0 0
sp_payment_common 12930 12930 0 0.00 1 6635 23 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 8211 280 0 0
sp_qd_handle_on_invoice_upd_sts 10566 10566 0 0.00 0 20724 331 0 0
sp_qd_handle_on_reserve_upd_sts 9919 9919 0 0.00 0 14425 48 0 0
sp_reserve_write_off 9919 9919 0 0.00 6 14471 177 0 0
sp_supplier_invoice 5345 5345 0 0.00 6 89954 3338 0 0
sp_supplier_order 5418 5418 0 0.00 5 53078 1496 0 0
srv_aggregate_perf_data 118 118 0 0.00 2880 93405 13342 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1496 149782 46946 0 0
srv_find_qd_qs_mism 1050187 1050187 0 0.00 0 9350 5 0 0
srv_make_invnt_saldo 467 467 0 0.00 46 109907 3395 0 0
srv_make_money_saldo 740 740 0 0.00 1 15643 552 0 0
srv_recalc_idx_stat 5 5 0 0.00 139425 391062 273351 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 15521 180 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 8211 135 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 693 8 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 3662 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 7606 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 152 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 893 12 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 42021 1465 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 1115 6 0 0
t$perf-norm:sp_client_order 20466 20466 0 0.00 0 10419 17 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 5892 15 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 7374 12 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 5510 8 0 0
t$perf-norm:sp_reserve_write_off 9919 9919 0 0.00 0 30664 14 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 5500 17 0 0
t$perf-norm:sp_supplier_order 5418 5418 0 0.00 0 10784 17 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 1844 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 594 16 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1030 7 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 6999 19 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9980 9756 224 2.24 0 5504 19 0 224
v_all_customers 38026 38026 0 0.00 0 51 1 0 0
v_all_suppliers 29702 29702 0 0.00 0 104 1 0 0
v_all_wares 40932 40932 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 112 5 0 0
v_cancel_client_order 3576 3576 0 0.00 0 3741 8 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 302 3 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 3066 9 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 241 4 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 3760 26 0 18
v_cancel_supplier_order 806 806 0 0.00 0 247 3 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2233 12 0 0
v_max_id_clo_ord 5418 5418 0 0.00 0 504 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 2629 9 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 6901 4 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 237 2 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 6030 5 0 0
v_min_id_clo_ord 5418 5418 0 0.00 0 7071 7 0 0
v_min_id_clo_res 991 991 0 0.00 0 3427 15 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 5054 7 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 6032 14 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 6304 15 0 0
v_our_firm 10014 10014 0 0.00 0 17 1 0 0
v_reserve_write_off 19838 19838 0 0.00 0 7024 6 0 0
x_lock_depdocs_on_canc_invoice 11530 11530 0 0.00 0 25335 116 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 14549 368 0 0
x_make_qstorno_1000_1200 5418 5418 0 0.00 2 53065 1416 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 2 89940 3182 0 0
x_make_qstorno_2100_3300 450503 450503 0 0.00 0 140412 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2021 10:38:57 to 12-08-2021 10:39:01.

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:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 22798 8192000000 9694949808 9988603904 778115280 8.674 100 83 167 1 806670016 7356192 21198544
08:09:38 26307 8192000000 9746603936 10048434176 823170544 9.130 100 86 172 0 855348240 11482688 20695008
08:11:08 12603 8192000000 9783452640 10096287744 851631280 9.416 100 84 168 2 888877040 17650000 19595760
08:12:25 12035 8192000000 9837584800 10164670464 889886304 9.798 100 79 158 1 952913520 45159808 17867408
08:13:42 6653 8192000000 9903502864 10240237568 955629184 10.446 100 87 171 1 1019495968 45528224 18338560
08:14:52 4849 8192000000 9971283968 10322358272 991028624 10.791 100 90 180 2 1079090160 69933792 18127744
08:15:59 3267 8192000000 10010188944 10372497408 1016616336 11.039 100 85 170 0 1125396848 91747504 17033008
08:17:04 2473 8192000000 10021649536 10387443712 1036299824 11.229 100 82 165 1 1128668304 75830288 16538192
08:18:08 960 8192000000 10047124608 10415415296 1061604544 11.472 100 90 181 1 1159080464 79369664 18106256
08:19:10 7534 8192000000 9994762848 10385788928 1066354816 11.517 100 45 93 1 1101757584 25998864 9403904
08:20:18 8836 8192000000 9983903248 10386411520 1072663072 11.578 100 26 52 0 1110657456 32206608 5787776
08:21:28 7153 8192000000 9978880176 10386186240 1075468032 11.604 100 30 61 1 1100648736 18355408 6825296
08:22:42 28683 8192000000 9976003344 10385641472 1082537456 11.672 100 38 75 0 1114083328 23316464 8229408
08:24:11 4192 8192000000 10037661712 10436509696 1093616288 11.777 100 46 93 0 1141923104 38163648 10143168
08:25:16 5074 8192000000 10013661360 10412756992 1098889008 11.827 100 42 81 3 1126935168 19105632 8940528
08:26:22 4561 8192000000 9995780240 10396930048 1072127040 11.572 99 31 59 1 1102831600 24284288 6420272
08:27:31 23954 8192000000 10029239584 10439704576 1084999424 11.695 100 47 93 3 1127128624 32192720 9936480
08:28:56 4558 8192000000 9961594112 10348793856 1016612432 11.039 96 65 127 0 1044439280 13155920 14670928
08:30:07 5002 8192000000 9933277232 10313723904 980806096 10.692 95 52 102 1 1019585296 28421392 10357808
08:31:12 81138 8192000000 9941432560 10335592448 1012761264 11.002 98 22 44 0 1046417680 29031648 4624768
08:33:36 3321 8192000000 9978033968 10360082432 1047391952 11.336 100 47 94 3 1083367536 25622736 10352848
08:34:41 10471 8192000000 9976804672 10381680640 1063427424 11.489 100 41 81 0 1098075376 25786848 8861104
08:35:53 75818 8192000000 9998441664 10410553344 1071666992 11.568 100 28 54 0 1102792496 25703584 5421920
08:38:14 6426 8192000000 10033545216 10435125248 1100456256 11.842 100 51 101 2 1145417776 34354480 10607040
08:39:24 11073 8192000000 10073439024 10481852416 1117449664 12.003 100 62 123 3 1181672016 50745616 13476736
08:40:41 25665 8192000000 10107358464 10519040000 1136122448 12.179 100 47 93 1 1197679184 51322464 10234272
08:42:10 30235 8192000000 10157571696 10579763200 1146934768 12.281 100 48 96 1 1246005952 88726704 10344480
08:44:00 8635 8192000000 10211913536 10623905792 1160220672 12.405 100 63 126 0 1313427312 139317136 13889504
08:45:20 3747 8192000000 10184823424 10597728256 1155909664 12.365 100 63 124 1 1284795872 115165056 13721152
08:46:30 11704 8192000000 10219846160 10648731648 1165159008 12.452 100 59 118 0 1321849376 143328000 13362368
08:47:45 3181 8192000000 10210814080 10642231296 1162715808 12.429 100 46 92 2 1313061616 139809344 10536464
08:48:55 6937 8192000000 10256977792 10671398912 1177966768 12.571 100 54 107 0 1358108944 168508672 11633504
08:50:14 2990 8192000000 10307044640 10713894912 1161583888 12.418 99 82 164 0 1397347808 217092800 18671120
08:51:21 3450 8192000000 10318174768 10732240896 1177366000 12.566 100 73 146 0 1429335232 235054736 16914496
08:52:30 2832 8192000000 10335865424 10768121856 1171532016 12.511 100 61 123 1 1437873440 251721776 14619648
08:53:46 1922 8192000000 10374869936 10795868160 1151573168 12.324 98 89 177 2 1454862272 283281888 20007216
08:54:53 1551 8192000000 10413517264 10818875392 1183184608 12.620 100 75 150 0 1508048944 307379872 17484464
08:55:57 9924 8192000000 10351930544 10787237888 1158793552 12.392 99 35 70 0 1448746304 280620400 9332352
08:57:13 3565 8192000000 10414348400 10822164480 1162635984 12.428 99 74 148 0 1500598816 320692832 17270000
08:58:21 1495 8192000000 10456166208 10855407616 1167586576 12.474 100 88 174 0 1546956848 359064256 20306016
08:59:26 22477 8192000000 10388546448 10824716288 1157522864 12.380 100 37 74 0 1476349616 309262496 9564256
09:00:54 12812 8192000000 10343023152 10800943104 1141550656 12.230 100 31 62 1 1455783248 305785024 8447568
09:02:12 35730 8192000000 10347411936 10825658368 1110847072 11.940 98 22 44 3 1454381008 337842320 5691616
09:03:52 26566 8192000000 10270919696 10706022400 1089943792 11.742 99 19 38 0 1376127616 281312400 4871424
09:05:20 75673 8192000000 10075110800 10538459136 1097593072 11.815 99 13 26 1 1186452608 86184640 2674896
09:07:36 56423 8192000000 10053990592 10475909120 1079949536 11.647 97 19 37 3 1098920768 15089680 3881552
09:09:41 125380 8192000000 10058131376 10483744768 1099192576 11.830 98 21 43 2 1130300944 26701520 4406848
09:13:26 63316 8192000000 10087600048 10511548416 1123760720 12.063 99 63 121 4 1169583216 32062960 13759536
09:15:37 47184 8192000000 10080181376 10537611264 1106484800 11.899 98 18 36 0 1168719344 58646816 3587728
09:17:25 111581 8192000000 10134615696 10603950080 1139571920 12.212 100 22 44 0 1237700288 92916464 5211904
09:20:18 69807 8192000000 10087281328 10547347456 1127696288 12.100 99 14 28 0 1204677168 73889680 3091200
09:23:08 89499 8192000000 10119614128 10624155648 1147142672 12.283 100 14 28 0 1237625696 87305296 3177728
09:25:56 22474 8192000000 10104663344 10581934080 1142916864 12.243 100 50 100 0 1230801248 77155360 10729024
09:27:19 45797 8192000000 10073274944 10519990272 1134527344 12.164 99 15 30 0 1174037056 36573184 2936528
09:29:09 42726 8192000000 10124297600 10560299008 1136969648 12.187 99 31 62 2 1201677568 58453216 6254704
09:30:52 56584 8192000000 10120562688 10613485568 1144607152 12.259 100 10 20 0 1210825568 64115360 2103056
09:32:48 50682 8192000000 10093588048 10564108288 1100888896 11.846 96 18 31 3 1144122800 40309760 2924144
09:34:42 91266 8192000000 10061828448 10494951424 1128474336 12.107 99 26 52 0 1160924096 27048384 5401376
09:37:25 21332 8192000000 10063155808 10501419008 1125717840 12.081 98 43 87 0 1148900096 13164496 10017760
09:38:57 73437 8192000000 10080156208 10567684096 1117550272 12.004 98 10 20 0 1196298304 76398800 2349232
09:41:11 66583 8192000000 10091518192 10543607808 1128012704 12.103 99 12 24 0 1183917040 53040896 2863440
09:44:08 48162 8192000000 10080746800 10529284096 1128507232 12.107 98 15 30 0 1175064848 43592208 2965408
09:46:01 53962 8192000000 10132401792 10571620352 1087592272 11.720 95 25 50 1 1167391360 74622224 5176864
09:48:03 50254 8192000000 10078331056 10591215616 1070010000 11.552 95 15 30 0 1174472816 100833712 3629104
09:49:53 33231 8192000000 10047096608 10484461568 1083356528 11.679 97 21 42 0 1123293344 35778224 4158592
09:51:28 52703 8192000000 10056940144 10490425344 1096306848 11.803 98 28 56 0 1161203568 59344000 5552720
09:53:22 43737 8192000000 10103371936 10543976448 1111556480 11.947 98 37 74 2 1217040704 97472576 8011648
09:55:10 69830 8192000000 10021185008 10458324992 1074763648 11.598 96 14 28 0 1100269488 22674288 2831552
09:57:34 21259 8192000000 10078761408 10517131264 1094258160 11.783 98 42 84 3 1160691456 57589232 8844064
10:01:12 58775 8192000000 10031532464 10484355072 1099537392 11.833 99 12 24 2 1134449696 32582256 2330048
10:03:30 76344 8192000000 10061643232 10529337344 1114349200 11.974 99 14 27 0 1178312592 61150848 2812544
10:05:47 69341 8192000000 10110272240 10557542400 1123525088 12.060 99 25 50 0 1178952512 49993376 5434048
10:08:00 49281 8192000000 10118734064 10582867968 1122018544 12.046 99 22 44 3 1206286048 79480112 4787392
10:09:50 79821 8192000000 10121839120 10566438912 1137085680 12.188 100 17 35 0 1223145264 82164336 3895248
10:12:12 55724 8192000000 10114448112 10593923072 1113008592 11.961 97 14 28 0 1219602080 104017776 2575712
10:14:08 41160 8192000000 10099013664 10549469184 1150202048 12.311 100 26 52 0 1208474032 53167808 5104176
10:15:53 149782 8192000000 10065198384 10512859136 1136099312 12.179 99 15 30 1 1176637568 37374576 3163680
10:19:24 69221 8192000000 10093175632 10575740928 1149298128 12.303 100 11 22 0 1195829856 43855328 2676400
10:21:33 54392 8192000000 10131873328 10576015360 1156945648 12.375 100 34 68 0 1210166416 45632672 7588096
10:23:32 34786 8192000000 10123209232 10564943872 1146417936 12.276 99 35 70 1 1200524320 46053408 8052976
10:25:28 120202 8192000000 10086609696 10531999744 1114357360 11.974 96 9 18 0 1201256352 84773632 2125360
10:28:29 85180 8192000000 10109064512 10634989568 1116498288 11.994 96 9 18 1 1201899232 83473472 1927472
10:30:56 42687 8192000000 10123815936 10591801344 1148452800 12.295 100 24 48 0 1230559568 77534944 4571824
10:32:40 111877 8192000000 10088649632 10587471872 1151677408 12.325 100 12 24 0 1198866992 44645616 2543968
10:35:46 67290 8192000000 10094937760 10549993472 1103412064 11.870 96 18 36 0 1154811344 47716384 3682896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-08-2021 10:39:03 to 12-08-2021 10:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          234047
MON$OLDEST_ACTIVE               235637
MON$OLDEST_SNAPSHOT             235637
MON$NEXT_TRANSACTION            235637
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                       2059488
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2568 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2568 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2568 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2568 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:08 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:08 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:12 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:12 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:12 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Aug 12 10:37:13 2021
: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'

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